Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/288/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC

5 tests failed.
FAILED:  org.apache.solr.core.TestLazyCores.testNoCommit

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([860D18A1E60DB91C:596DB9702D2ADAB9]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:901)
        at org.apache.solr.core.TestLazyCores.check10(TestLazyCores.java:847)
        at 
org.apache.solr.core.TestLazyCores.testNoCommit(TestLazyCores.java:829)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result[@numFound='10']
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">1</int><lst name="params"><str 
name="q">*:*</str></lst></lst><result name="response" numFound="0" 
start="0"></result>
</response>

        request was:q=*:*
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:894)
        ... 41 more


FAILED:  junit.framework.TestSuite.org.apache.solr.core.TestLazyCores

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:     1) 
Thread[id=23340, name=searcherExecutor-8388-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]         at sun.misc.Unsafe.park(Native Method)        
 at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)         
at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 
        at java.lang.Thread.run(Thread.java:748)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.core.TestLazyCores: 
   1) Thread[id=23340, name=searcherExecutor-8388-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)
        at __randomizedtesting.SeedInfo.seed([860D18A1E60DB91C]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.core.TestLazyCores

Error Message:
There are still zombie threads that couldn't be terminated:    1) 
Thread[id=23340, name=searcherExecutor-8388-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]         at sun.misc.Unsafe.park(Native Method)        
 at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)         
at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 
        at java.lang.Thread.run(Thread.java:748)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   1) Thread[id=23340, name=searcherExecutor-8388-thread-1, state=WAITING, 
group=TGRP-TestLazyCores]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)
        at __randomizedtesting.SeedInfo.seed([860D18A1E60DB91C]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.MoreLikeThisHandlerTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001\spellcheckerFQCN:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001\spellcheckerFQCN
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001\spellcheckerFQCN:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001\spellcheckerFQCN
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001

        at __randomizedtesting.SeedInfo.seed([860D18A1E60DB91C]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  junit.framework.TestSuite.org.apache.solr.update.TestUpdate

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog\tlog.0000000000000000008:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog\tlog.0000000000000000008
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog\tlog.0000000000000000008:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\tlog\tlog.0000000000000000008
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001

        at __randomizedtesting.SeedInfo.seed([860D18A1E60DB91C]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11685 lines...]
   [junit4] Suite: org.apache.solr.handler.MoreLikeThisHandlerTest
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001
   [junit4]   2> 237240 WARN  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
   [junit4]   2> 237240 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 237242 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 237242 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 237242 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 237242 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 237341 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 237397 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 237633 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 237791 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1300e6d
   [junit4]   2> 237811 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1300e6d
   [junit4]   2> 237811 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1300e6d
   [junit4]   2> 237816 INFO  (coreLoadExecutor-1255-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 237817 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 237925 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 237968 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 238189 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 238208 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 238209 INFO  (coreLoadExecutor-1255-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@1300e6d
   [junit4]   2> 238210 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 238210 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001\]
   [junit4]   2> 238212 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=32, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4652322994735535]
   [junit4]   2> 238346 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 238346 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 238347 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 238348 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 238354 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=38.8994140625, 
floorSegmentMB=1.669921875, forceMergeDeletesPctAllowed=19.702705332650496, 
segmentsPerTier=7.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 238354 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@22f8b[collection1] 
main]
   [junit4]   2> 238356 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 238363 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 238395 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 238396 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 238396 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 238396 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 238396 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 238397 INFO  (coreLoadExecutor-1255-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1583208922848690176
   [junit4]   2> 238397 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 238397 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 238397 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 238398 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 238398 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 238402 INFO  
(searcherExecutor-1256-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@22f8b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 238403 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 238405 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testInterface
   [junit4]   2> 238413 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[42 (1583208922860224512)]} 0 4
   [junit4]   2> 238420 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[43 (1583208922871758848)]} 0 1
   [junit4]   2> 238421 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[44 (1583208922872807424)]} 0 0
   [junit4]   2> 238422 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[45 (1583208922874904576)]} 0 0
   [junit4]   2> 238425 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[46 (1583208922875953152)]} 0 2
   [junit4]   2> 238426 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583208922879098880,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 238426 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@16da699 
commitCommandVersion:1583208922879098880
   [junit4]   2> 238448 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1deaceb[collection1] main]
   [junit4]   2> 238452 INFO  (searcherExecutor-1256-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1deaceb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C5)))}
   [junit4]   2> 238453 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 238453 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 27
   [junit4]   2> 238467 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:42&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true}
 hits=1 status=0 QTime=11
   [junit4]   2> 238473 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:42&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true&mlt.boost=true}
 hits=1 status=0 QTime=3
   [junit4]   2> 238476 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:44&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true&mlt.boost=true}
 hits=1 status=0 QTime=1
   [junit4]   2> 238479 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:44&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true&mlt.boost=true&debugQuery=true}
 hits=1 status=0 QTime=2
   [junit4]   2> 238486 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!field+f%3Did}44&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true&mlt.boost=true}
 hits=1 status=0 QTime=1
   [junit4]   2> 238490 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:42&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true&mlt.boost=true&mlt.qf=name^5.0+subword^0.1}
 hits=1 status=0 QTime=3
   [junit4]   2> 238497 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!field+f%3Did}44&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true&mlt.boost=true&mlt.qf=name^5.0+subword^0.1&qt=/mlt}
 status=0 QTime=5
   [junit4]   2> 238500 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q={!field+f%3Did}44&mlt=true&mlt.fl=name,subword&mlt.interestingTerms=details&mlt.mintf=1&mlt.mindf=1&indent=true&mlt.boost=true&mlt.qf=name^5.0+subword^0.1&qt=/mlt&debugQuery=true}
 status=0 QTime=1
   [junit4]   2> 238500 INFO  
(TEST-MoreLikeThisHandlerTest.testInterface-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testInterface
   [junit4]   2> 238501 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 238501 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=21935524
   [junit4]   2> 238501 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 238501 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1fdd9dc: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@5d887d
   [junit4]   2> 238508 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 238508 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@58f4b6: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@1dcd769
   [junit4]   2> 238515 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 238515 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1bf224: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@382a7d
   [junit4]   2> 238517 INFO  (coreCloseExecutor-1261-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1cafc88
   [junit4]   2> 238517 INFO  (coreCloseExecutor-1261-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=30080136
   [junit4]   2> 238517 INFO  (coreCloseExecutor-1261-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@17f502a: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1d57349
   [junit4]   2> 238538 INFO  
(SUITE-MoreLikeThisHandlerTest-seed#[860D18A1E60DB91C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=RandomSimilarity(queryNorm=false): {}, locale=pt-BR, 
timezone=America/Guayaquil
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=87105176,total=213884928
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestDocBasedVersionConstraints, TestLuceneMatchVersion, TestLRUCache, 
CdcrRequestHandlerTest, TestSolrCloudWithKerberosAlt, AddBlockUpdateTest, 
TestDynamicLoading, FacetPivotSmallTest, TestAnalyzedSuggestions, 
TestValueSourceCache, ClassificationUpdateProcessorTest, TestStandardQParsers, 
TestComplexPhraseLeadingWildcard, TestZkChroot, SolrPluginUtilsTest, 
AtomicUpdateProcessorFactoryTest, CheckHdfsIndexTest, 
CachingDirectoryFactoryTest, QueryElevationComponentTest, TestFieldCache, 
TestTolerantUpdateProcessorCloud, TestReqParamsAPI, HdfsLockFactoryTest, 
FullSolrCloudDistribCmdsTest, TestJettySolrRunner, BadComponentTest, 
TestStressLucene, BufferStoreTest, CollectionStateFormat2Test, 
TestSearcherReuse, TestBM25SimilarityFactory, TestFastLRUCache, 
TestRecoveryHdfs, TestStressLiveNodes, SOLR749Test, TestDistributedGrouping, 
UninvertDocValuesMergePolicyTest, TestConfigSetProperties, QueryEqualityTest, 
DocValuesMultiTest, TestSSLRandomization, MoreLikeThisHandlerTest]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=MoreLikeThisHandlerTest -Dtests.seed=860D18A1E60DB91C 
-Dtests.slow=true -Dtests.locale=pt-BR -Dtests.timezone=America/Guayaquil 
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J1 | MoreLikeThisHandlerTest (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001\spellcheckerFQCN:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001\spellcheckerFQCN
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.MoreLikeThisHandlerTest_860D18A1E60DB91C-001\init-core-data-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([860D18A1E60DB91C]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [54/747 (1!)] on J1 in 1.34s, 1 test, 1 error <<< 
FAILURES!

[...truncated 238 lines...]
   [junit4] Suite: org.apache.solr.update.TestUpdate
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001
   [junit4]   2> 482536 WARN  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 482536 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 482541 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 482542 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 482543 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 482543 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 482597 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 482615 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 482700 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 482798 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c5b078
   [junit4]   2> 482808 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c5b078
   [junit4]   2> 482808 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c5b078
   [junit4]   2> 482810 INFO  (coreLoadExecutor-2014-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 482812 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 482873 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 482886 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 482968 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 482981 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 482981 INFO  (coreLoadExecutor-2014-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@1c5b078
   [junit4]   2> 482981 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 482981 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestUpdate_860D18A1E60DB91C-001\init-core-data-001\]
   [junit4]   2> 482983 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=23, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=64.171875, 
floorSegmentMB=0.375, forceMergeDeletesPctAllowed=2.2116067442905827, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 482986 WARN  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 483074 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 483074 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 483082 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 483082 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 483082 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.88268947203186]
   [junit4]   2> 483082 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f730c7[collection1] 
main]
   [junit4]   2> 483082 WARN  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 483086 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 483086 INFO  (coreLoadExecutor-2014-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1583209179489763328
   [junit4]   2> 483087 INFO  
(searcherExecutor-2015-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f730c7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 483089 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 483092 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testUpdatableDocs
   [junit4]   2> 483093 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 483095 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@f1e4b1[collection1] realtime]
   [junit4]   2> 483095 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 2
   [junit4]   2> 483098 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179499200512)]} 0 2
   [junit4]   2> 483099 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179503394816)]} 0 0
   [junit4]   2> 483100 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179504443392)]} 0 0
   [junit4]   2> 483100 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is,copyfield_*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483101 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179505491968)]} 0 0
   [junit4]   2> 483102 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483102 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179506540544,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 483108 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@302e6f[collection1] main]
   [junit4]   2> 483109 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483112 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@302e6f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C4/3:delGen=1)))}
   [junit4]   2> 483113 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 11
   [junit4]   2> 483117 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=id,*_i,*_is,copyfield_*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 483118 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483119 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=2&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483120 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179524366336)]} 0 1
   [junit4]   2> 483121 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1583209179524366336&versions=true&wt=json&indent=true}{add=[1 
(1583209179526463488)]} 0 0
   [junit4]   2> 483122 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483122 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179527512064)]} 0 0
   [junit4]   2> 483122 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483123 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179528560640)]} 0 0
   [junit4]   2> 483123 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483124 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{delete=[1 (-1583209179529609216)]} 0 0
   [junit4]   2> 483124 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483125 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179529609217)]} 0 0
   [junit4]   2> 483125 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483126 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179530657792)]} 0 0
   [junit4]   2> 483126 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179531706368)]} 0 0
   [junit4]   2> 483127 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483127 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179532754944)]} 0 0
   [junit4]   2> 483128 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483129 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179533803520)]} 0 0
   [junit4]   2> 483129 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483130 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179534852096)]} 0 0
   [junit4]   2> 483130 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483130 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483131 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching Invalid update of id 
field
   [junit4]   2> 483131 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483132 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179537997824,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483132 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179537997824
   [junit4]   2> 483139 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1d7f793[collection1] main]
   [junit4]   2> 483140 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483143 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d7f793[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.2.0):C10/9:delGen=1)))}
   [junit4]   2> 483143 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 11
   [junit4]   2> 483144 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=id&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 483145 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 483145 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@15358e0[collection1] realtime]
   [junit4]   2> 483146 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 0
   [junit4]   2> 483146 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179552677888,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483146 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179552677888
   [junit4]   2> 483149 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@25c9b4[collection1] main]
   [junit4]   2> 483149 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483152 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@25c9b4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 483153 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 483156 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179561066496)]} 0 2
   [junit4]   2> 483156 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179563163648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483156 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179563163648
   [junit4]   2> 483159 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7a730d[collection1] main]
   [junit4]   2> 483160 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483164 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7a730d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.2.0):C1)))}
   [junit4]   2> 483164 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 8
   [junit4]   2> 483167 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179572600832)]} 0 2
   [junit4]   2> 483168 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179575746560,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483168 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179575746560
   [junit4]   2> 483171 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@c2c368[collection1] main]
   [junit4]   2> 483175 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483175 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@c2c368[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.2.0):C1)))}
   [junit4]   2> 483176 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 8
   [junit4]   2> 483179 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179585183744)]} 0 2
   [junit4]   2> 483179 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179587280896,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483179 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179587280896
   [junit4]   2> 483184 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@18a60a0[collection1] main]
   [junit4]   2> 483187 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483188 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@18a60a0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.2.0):C1)))}
   [junit4]   2> 483189 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 9
   [junit4]   2> 483189 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is,copyfield_*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483192 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179598815232)]} 0 1
   [junit4]   2> 483192 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179600912384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483192 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179600912384
   [junit4]   2> 483195 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@34685e[collection1] main]
   [junit4]   2> 483196 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483206 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@34685e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.2.0):C1)))}
   [junit4]   2> 483206 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 14
   [junit4]   2> 483207 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483207 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179616641024,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 483207 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483207 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 483208 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=id,*_i,*_is,copyfield_*&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 483209 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483209 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=2&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483212 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179619786752)]} 0 2
   [junit4]   2> 483212 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179621883904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483212 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179621883904
   [junit4]   2> 483215 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1c9c09c[collection1] main]
   [junit4]   2> 483217 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483220 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1c9c09c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.2.0):C1)))}
   [junit4]   2> 483221 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 8
   [junit4]   2> 483224 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1583209179619786752&versions=true&wt=json&indent=true}{add=[1 
(1583209179632369664)]} 0 2
   [junit4]   2> 483224 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179634466816,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483224 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179634466816
   [junit4]   2> 483228 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@cb3565[collection1] main]
   [junit4]   2> 483231 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483233 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@cb3565[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(7.2.0):C1)))}
   [junit4]   2> 483233 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 9
   [junit4]   2> 483234 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483236 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179644952576)]} 0 2
   [junit4]   2> 483237 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179648098304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483237 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179648098304
   [junit4]   2> 483241 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@b49a83[collection1] main]
   [junit4]   2> 483241 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483244 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@b49a83[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(7.2.0):C1)))}
   [junit4]   2> 483245 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 8
   [junit4]   2> 483246 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483248 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179657535488)]} 0 2
   [junit4]   2> 483248 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179659632640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483248 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179659632640
   [junit4]   2> 483252 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@11c408e[collection1] main]
   [junit4]   2> 483253 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483256 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@11c408e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(7.2.0):C1)))}
   [junit4]   2> 483257 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 8
   [junit4]   2> 483257 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,*_i,*_is&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483259 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{delete=[1 (-1583209179670118400)]} 0 
1
   [junit4]   2> 483259 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179671166976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483259 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179671166976
   [junit4]   2> 483260 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1877dd4[collection1] main]
   [junit4]   2> 483260 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483264 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1877dd4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 483264 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 483265 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483267 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179677458432)]} 0 1
   [junit4]   2> 483267 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179679555584,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483267 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179679555584
   [junit4]   2> 483269 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7d3ecc[collection1] main]
   [junit4]   2> 483270 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483273 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d3ecc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(7.2.0):C1)))}
   [junit4]   2> 483273 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 483274 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483276 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179687944192)]} 0 1
   [junit4]   2> 483277 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179690041344,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483277 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179690041344
   [junit4]   2> 483278 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1d688c3[collection1] main]
   [junit4]   2> 483279 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483282 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d688c3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.2.0):C1)))}
   [junit4]   2> 483282 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 483285 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179697381376)]} 0 1
   [junit4]   2> 483285 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179698429952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483286 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179698429952
   [junit4]   2> 483289 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@bbd456[collection1] main]
   [junit4]   2> 483295 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483299 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@bbd456[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(7.2.0):C1)))}
   [junit4]   2> 483300 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 14
   [junit4]   2> 483301 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483304 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179715207168)]} 0 2
   [junit4]   2> 483304 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179718352896,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483304 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179718352896
   [junit4]   2> 483306 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@145ec79[collection1] main]
   [junit4]   2> 483307 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483310 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@145ec79[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_d(7.2.0):C1)))}
   [junit4]   2> 483310 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 483311 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483313 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179726741504)]} 0 1
   [junit4]   2> 483314 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179728838656,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483314 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179728838656
   [junit4]   2> 483316 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a8dd60[collection1] main]
   [junit4]   2> 483317 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483320 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1a8dd60[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(7.2.0):C1)))}
   [junit4]   2> 483320 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 483321 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483323 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1583209179736178688)]} 0 1
   [junit4]   2> 483323 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179738275840,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483323 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 
commitCommandVersion:1583209179738275840
   [junit4]   2> 483326 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@17ece2[collection1] main]
   [junit4]   2> 483327 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483329 INFO  (searcherExecutor-2015-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17ece2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_f(7.2.0):C1)))}
   [junit4]   2> 483330 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 483330 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483331 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 483331 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching Invalid update of id 
field
   [junit4]   2> 483331 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179746664448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483331 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 483332 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483332 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 483332 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val*&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 483332 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1583209179747713024,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 483333 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 483333 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 483333 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 483333 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&fl=id&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 483334 INFO  
(TEST-TestUpdate.testUpdatableDocs-seed#[860D18A1E60DB91C]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testUpdatableDocs
   [junit4]   2> 483335 INFO  
(TEST-TestUpdate.testUpdateLogThrowsForUnknownTypes-seed#[860D18A1E60DB91C]) [  
  ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateLogThrowsForUnknownTypes
   [junit4]   2> 483338 INFO  
(TEST-TestUpdate.testUpdateLogThrowsForUnknownTypes-seed#[860D18A1E60DB91C]) [  
  ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateLogThrowsForUnknownTypes
   [junit4]   2> 483339 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 483339 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=17682944
   [junit4]   2> 483339 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 483339 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@a43609: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@1cc9f37
   [junit4]   2> 483343 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 483343 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@dc32ce: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3191df
   [junit4]   2> 483347 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 483347 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@a6df73: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@d39586
   [junit4]   2> 483348 INFO  (coreCloseExecutor-2020-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@631773
   [junit4]   2> 483348 INFO  (coreCloseExecutor-2020-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=6494067
   [junit4]   2> 483348 INFO  (coreCloseExecutor-2020-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1d6c630: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@f65bd1
   [junit4]   2> 483357 INFO  (coreCloseExecutor-2020-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 483357 INFO  (coreCloseExecutor-2020-thread-1) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@d10087 commitCommandVersion:0
   [junit4]   2> 483363 INFO  (SUITE-TestUpdate-seed#[860D18A1E60DB91C]-worker) 
[    ] o.a.s.SolrTestCaseJ4 
------------------------------------------------------- Done waiting for 
tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=RandomSimilarity(queryNorm=false): {}, locale=ca, timezone=Australia/Darwin
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=192321120,total=305000448
   [junit4]   2> NOTE: All tests run in this JVM: [HighlighterTest, 
RequiredFieldsTest, BadIndexSchemaTest, CollectionReloadTest, 
TestSolrCloudWithHadoopAuthPlugin, TestCloudDeleteByQuery, 
AssignBackwardCompatibilityTest, ForceLeaderTest, ImplicitSnitchTest, 
TestRequestStatusCollectionAPI, TestDownShardTolerantSearch, CursorPagingTest, 
SmileWriterTest, TestUseDocValuesAsStored, TestLMDirichletSimilarityFactory, 
TestManagedSynonymFilterFactory, DateMathParserTest, DataDrivenBlockJoinTest, 
LeaderFailureAfterFreshStartTest, SpatialRPTFieldTypeTest, TestStreamBody, 
HdfsBasicDistributedZk2Test, TestCollationFieldDocValues, 
PreAnalyzedFieldManagedSchemaCloudTest, 
TestPerFieldSimilarityWithDefaultOverride, TestImplicitCoreProperties, 
SpellCheckCollatorTest, TriLevelCompositeIdRoutingTest, 
AnalysisErrorHandlingTest, TestSlowCompositeRe

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

hread-1, state=WAITING, group=TGRP-TestLazyCores]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]   2>         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Nov 05, 2017 7:47:50 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=23340, name=searcherExecutor-8388-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]   2> Nov 05, 2017 7:47:53 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> SEVERE: There are still zombie threads that couldn't be 
terminated:
   [junit4]   2>    1) Thread[id=23340, name=searcherExecutor-8388-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]   2>         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{v_t=PostingsFormat(name=LuceneVarGapFixedInterval), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), v_s=FST50, 
v_f=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1789, 
maxMBSortInHeap=5.295084598197091, sim=RandomSimilarity(queryNorm=true): {}, 
locale=id-ID, timezone=Iran
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=2,free=212991704,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestDocBasedVersionConstraints, TestLuceneMatchVersion, TestLRUCache, 
CdcrRequestHandlerTest, TestSolrCloudWithKerberosAlt, AddBlockUpdateTest, 
TestDynamicLoading, FacetPivotSmallTest, TestAnalyzedSuggestions, 
TestValueSourceCache, ClassificationUpdateProcessorTest, TestStandardQParsers, 
TestComplexPhraseLeadingWildcard, TestZkChroot, SolrPluginUtilsTest, 
AtomicUpdateProcessorFactoryTest, CheckHdfsIndexTest, 
CachingDirectoryFactoryTest, QueryElevationComponentTest, TestFieldCache, 
TestTolerantUpdateProcessorCloud, TestReqParamsAPI, HdfsLockFactoryTest, 
FullSolrCloudDistribCmdsTest, TestJettySolrRunner, BadComponentTest, 
TestStressLucene, BufferStoreTest, CollectionStateFormat2Test, 
TestSearcherReuse, TestBM25SimilarityFactory, TestFastLRUCache, 
TestRecoveryHdfs, TestStressLiveNodes, SOLR749Test, TestDistributedGrouping, 
UninvertDocValuesMergePolicyTest, TestConfigSetProperties, QueryEqualityTest, 
DocValuesMultiTest, TestSSLRandomization, MoreLikeThisHandlerTest, 
TestClusterStateMutator, BigEndianAscendingWordDeserializerTest, 
TestDistribIDF, TestShardHandlerFactory, DeleteStatusTest, TestFaceting, 
SystemInfoHandlerTest, SampleTest, FileUtilsTest, 
HdfsTlogReplayBufferedWhileIndexingTest, BlockJoinFacetRandomTest, 
LukeRequestHandlerTest, TestQueryUtils, HdfsWriteToMultipleCollectionsTest, 
BasicDistributedZk2Test, TestElisionMultitermQuery, TestMergePolicyConfig, 
ReplicaListTransformerTest, HdfsNNFailoverTest, SolrCloudExampleTest, 
MetricUtilsTest, SpatialHeatmapFacetsTest, TestNamedUpdateProcessors, 
TestComponentsName, SpellCheckCollatorWithCollapseTest, 
TestClassicSimilarityFactory, TestCryptoKeys, TestNRTOpen, 
SolrMetricReporterTest, TestCharFilters, UtilsToolTest, TestCSVLoader, 
PingRequestHandlerTest, SolrGangliaReporterTest, ExternalFileFieldSortTest, 
SimpleFacetsTest, TestGroupingSearch, AliasIntegrationTest, 
TestManagedStopFilterFactory, DocExpirationUpdateProcessorFactoryTest, 
TestFreeTextSuggestions, TestSort, TestCustomSort, 
LeaderElectionIntegrationTest, TestSimpleTrackingShardHandler, 
HdfsBasicDistributedZkTest, TestWordDelimiterFilterFactory, 
StatelessScriptUpdateProcessorFactoryTest, PeerSyncTest, 
TestNumericTokenStream, PeerSyncWithIndexFingerprintCachingTest, 
TestRemoteStreaming, JvmMetricsTest, BlobRepositoryCloudTest, 
TestCollationField, TestCoreAdminApis, SystemLogListenerTest, BasicZkTest, 
RestartWhileUpdatingTest, TestBulkSchemaConcurrent, 
DistributedFacetPivotWhiteBoxTest, TestPullReplica, StatsReloadRaceTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestRequestForwarding, 
TermVectorComponentTest, TestLockTree, DistributedFacetPivotSmallAdvancedTest, 
CollectionsAPIDistributedZkTest, ChaosMonkeySafeLeaderTest, 
NodeLostTriggerTest, UpdateParamsTest, IndexSchemaRuntimeFieldTest, 
SpellPossibilityIteratorTest, TestPointFields, OverseerTaskQueueTest, 
TestHashPartitioner, TestBinaryResponseWriter, TestDocumentBuilder, 
OpenExchangeRatesOrgProviderTest, CoreSorterTest, TestIndexSearcher, 
TestFieldCacheVsDocValues, TestSerializedLuceneMatchVersion, 
TestClusterProperties, TestNestedDocsSort, AutoAddReplicasIntegrationTest, 
TestSolrConfigHandler, TestEmbeddedSolrServerConstructors, HttpPartitionTest, 
DistributedIntervalFacetingTest, TestFastOutputStream, 
DistributedMLTComponentTest, TestBlobHandler, TestGraphMLResponseWriter, 
SolrIndexSplitterTest, TestLeaderInitiatedRecoveryThread, 
TestSchemaSimilarityResource, AnalyticsQueryTest, TestReload, HLLUtilTest, 
DistributedQueueTest, TestNoOpRegenerator, JsonLoaderTest, TestHdfsUpdateLog, 
TestPushWriter, TestSolrCLIRunExample, HdfsChaosMonkeySafeLeaderTest, 
TestMacroExpander, TestFunctionQuery, TestZkAclsWithHadoopAuth, 
TestSolrConfigHandlerCloud, TestFoldingMultitermQuery, 
TestHdfsBackupRestoreCore, TestLMJelinekMercerSimilarityFactory, 
FileBasedSpellCheckerTest, CacheHeaderTest, RequestLoggingTest, 
TestUnifiedSolrHighlighter, TestDistributedMissingSort, MBeansHandlerTest, 
TestFieldCacheSort, DebugComponentTest, TestIntervalFaceting, 
TestCloudRecovery, LeaderFailoverAfterPartitionTest, TestNumericRangeQuery32, 
TestRandomFaceting, TestAuthenticationFramework, TestOrdValues, 
SimpleCollectionCreateDeleteTest, TestLeaderElectionWithEmptyReplica, 
LoggingHandlerTest, TestAddFieldRealTimeGet, SoftAutoCommitTest, 
TestExclusionRuleCollectionAccess, TestPartialUpdateDeduplication, 
EchoParamsTest, DOMUtilTest, FullHLLTest, BasicDistributedZkTest, 
TestCoreContainer, TestSolr4Spatial, SolrCoreTest, SpellCheckComponentTest, 
ConvertedLegacyTest, TestFiltering, BasicFunctionalityTest, TestLazyCores]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestLazyCores 
-Dtests.seed=860D18A1E60DB91C -Dtests.slow=true -Dtests.locale=id-ID 
-Dtests.timezone=Iran -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J1 | TestLazyCores (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.core.TestLazyCores: 
   [junit4]    >    1) Thread[id=23340, name=searcherExecutor-8388-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]    >         at java.lang.Thread.run(Thread.java:748)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([860D18A1E60DB91C]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=23340, name=searcherExecutor-8388-thread-1, 
state=WAITING, group=TGRP-TestLazyCores]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]    >         at java.lang.Thread.run(Thread.java:748)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([860D18A1E60DB91C]:0)
   [junit4] Completed [408/747 (3!)] on J1 in 39.78s, 9 tests, 3 errors <<< 
FAILURES!

[...truncated 44180 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to