Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1332/

2 tests failed.
FAILED:  
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences

Error Message:
Adding a policy with 'cores' attribute should not have succeeded.

Stack Trace:
java.lang.AssertionError: Adding a policy with 'cores' attribute should not 
have succeeded.
        at 
__randomizedtesting.SeedInfo.seed([389D3A4D00E5B7D1:9955646479A3B1DF]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences(AutoScalingHandlerTest.java:83)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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)


FAILED:  org.apache.solr.update.HardAutoCommitTest.testCommitWithin

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([389D3A4D00E5B7D1:824F553583CB59C4]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:890)
        at 
org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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=1]
        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><result name="response" numFound="0" 
start="0"></result>
</response>

        request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:883)
        ... 40 more




Build Log:
[...truncated 11729 lines...]
   [junit4] Suite: org.apache.solr.update.HardAutoCommitTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_389D3A4D00E5B7D1-001/init-core-data-001
   [junit4]   2> 461419 WARN  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 461424 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 461425 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 461427 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 461427 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 461471 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 461520 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 461726 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 461813 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c76aaa2
   [junit4]   2> 461825 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c76aaa2
   [junit4]   2> 461826 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c76aaa2
   [junit4]   2> 461839 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 461902 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 461950 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 462087 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 462094 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 462094 INFO  (coreLoadExecutor-1172-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@2c76aaa2
   [junit4]   2> 462094 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 462094 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_389D3A4D00E5B7D1-001/init-core-data-001/]
   [junit4]   2> 462096 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=24, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=91.7294921875, 
floorSegmentMB=0.8935546875, forceMergeDeletesPctAllowed=8.600203229093585, 
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7878558064007951
   [junit4]   2> 462187 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 462187 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 462188 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 462188 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 462189 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1219746815, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 462190 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@73ee5e5c[collection1] main]
   [junit4]   2> 462191 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 462202 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 462271 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 462271 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 462271 INFO  (coreLoadExecutor-1172-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1570792280886870016
   [junit4]   2> 462271 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 462271 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 462271 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 462272 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 462272 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 462272 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 462272 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 462273 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 462275 INFO  
(searcherExecutor-1173-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@73ee5e5c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 462277 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 462279 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 462279 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 462282 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d822769[collection1] realtime]
   [junit4]   2> 462282 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
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 3
   [junit4]   2> 462283 ERROR 
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException:
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 462284 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 462419 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 462464 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 462706 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 462713 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1
   [junit4]   2> 462732 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
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@2c76aaa2
   [junit4]   2> 462733 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_389D3A4D00E5B7D1-001/init-core-data-001/]
   [junit4]   2> 462824 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 462825 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 462826 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@26cd1338[collection1] main]
   [junit4]   2> 462827 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 462827 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 462831 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 462831 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1570792281474072576
   [junit4]   2> 462833 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 462834 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 462834 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 462834 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 462838 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 462838 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 462839 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=8, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 462839 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 462840 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 462840 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 462840 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 462843 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@26cd1338[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 462843 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3648aef2[collection1] main]
   [junit4]   2> 462843 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@20141030
   [junit4]   2> 462844 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=538185776
   [junit4]   2> 462853 INFO  
(searcherExecutor-1178-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3648aef2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 462858 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 
(1570792281499238400)]} 0 7
   [junit4]   2> 462859 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=0
   [junit4]   2> 462862 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 
(1570792281506578432)]} 0 11
   [junit4]   2> 462863 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=0
   [junit4]   2> 462863 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 
(-1570792281507627008)]} 0 12
   [junit4]   2> 462865 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 
QTime=1
   [junit4]   2> 462866 ERROR 
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
   [junit4]   2>        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">1</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2>        request 
was:q=id:529&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 462866 ERROR 
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: 
q=id:529&qt=standard&start=0&rows=20&version=2.2:java.lang.RuntimeException: 
REQUEST FAILED: xpath=//result[@numFound=1]
   [junit4]   2>        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">1</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2>        request 
was:q=id:529&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:883)
   [junit4]   2>        at 
org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method)
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 462866 INFO  
(TEST-HardAutoCommitTest.testCommitWithin-seed#[389D3A4D00E5B7D1]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=HardAutoCommitTest 
-Dtests.method=testCommitWithin -Dtests.seed=389D3A4D00E5B7D1 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
 -Dtests.locale=es-BO -Dtests.timezone=America/Argentina/ComodRivadavia 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.60s J2 | HardAutoCommitTest.testCommitWithin <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during 
query
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([389D3A4D00E5B7D1:824F553583CB59C4]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:890)
   [junit4]    >        at 
org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result[@numFound=1]
   [junit4]    >        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">1</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]    > </response>
   [junit4]    >        request 
was:q=id:529&qt=standard&start=0&rows=20&version=2.2
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:883)
   [junit4]    >        ... 40 more
   [junit4]   2> 462872 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 462872 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    x:collection1] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=528410199
   [junit4]   2> 462872 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 462873 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 462874 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 462875 INFO  
(SUITE-HardAutoCommitTest-seed#[389D3A4D00E5B7D1]-worker) [    x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 462875 INFO  
(coreCloseExecutor-1182-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1d1b13de
   [junit4]   2> 462876 INFO  
(coreCloseExecutor-1182-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 462876 INFO  
(coreCloseExecutor-1182-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@40428594 commitCommandVersion:0
   [junit4]   2> 462882 INFO  
(coreCloseExecutor-1182-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=488313822
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.update.HardAutoCommitTest_389D3A4D00E5B7D1-001
   [junit4]   2> Jun 21, 2017 5:50:55 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: 
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=30540, maxDocsPerChunk=842, blockSize=227), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=30540, blockSize=227)), sim=RandomSimilarity(queryNorm=true): {}, 
locale=es-BO, timezone=America/Argentina/ComodRivadavia
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=254221688,total=516947968
   [junit4]   2> NOTE: All tests run in this JVM: 
[StatelessScriptUpdateProcessorFactoryTest, MetricUtilsTest, 
OpenExchangeRatesOrgProviderTest, FullSolrCloudDistribCmdsTest, 
TestCloudDeleteByQuery, BooleanFieldTest, TestPolicyCloud, 
TestCloudManagedSchema, TestConfigSetImmutable, 
LeaderFailoverAfterPartitionTest, TestBinaryResponseWriter, 
BasicDistributedZk2Test, CdcrReplicationHandlerTest, SimpleFacetsTest, 
TestCollationFieldDocValues, TestPHPSerializedResponseWriter, AutoCommitTest, 
TestCollapseQParserPlugin, TestTrackingShardHandlerFactory, 
TestPerFieldSimilarityWithDefaultOverride, HardAutoCommitTest]
   [junit4] Completed [116/725 (1!)] on J2 in 1.90s, 1 test, 1 error <<< 
FAILURES!

[...truncated 1429 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/init-core-data-001
   [junit4]   2> 3232156 WARN  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3595 numCloses=3595
   [junit4]   2> 3232156 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 3232158 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 3232159 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001
   [junit4]   2> 3232160 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 3232161 INFO  (Thread-10473) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 3232161 INFO  (Thread-10473) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 3232162 ERROR (Thread-10473) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 3232261 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:58287
   [junit4]   2> 3232270 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 3232270 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 3232272 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4fdbfa6e{/solr,null,AVAILABLE}
   [junit4]   2> 3232272 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6f860149{/solr,null,AVAILABLE}
   [junit4]   2> 3232276 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@66a0a632{SSL,[ssl, 
http/1.1]}{127.0.0.1:58577}
   [junit4]   2> 3232276 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@38d8e592{SSL,[ssl, 
http/1.1]}{127.0.0.1:50934}
   [junit4]   2> 3232276 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.e.j.s.Server Started @3239665ms
   [junit4]   2> 3232276 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.e.j.s.Server Started @3239665ms
   [junit4]   2> 3232276 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=58577}
   [junit4]   2> 3232277 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50934}
   [junit4]   2> 3232277 ERROR (jetty-launcher-3519-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3232277 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 3232277 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3232277 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3232277 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-21T06:37:07.482Z
   [junit4]   2> 3232277 ERROR (jetty-launcher-3519-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3232277 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 3232278 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3232278 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3232278 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-21T06:37:07.483Z
   [junit4]   2> 3232280 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3232280 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3232291 INFO  (jetty-launcher-3519-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58287/solr
   [junit4]   2> 3232291 INFO  (jetty-launcher-3519-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58287/solr
   [junit4]   2> 3232351 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3232352 INFO  (jetty-launcher-3519-thread-1) 
[n:127.0.0.1:50934_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 3232352 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:58577_solr
   [junit4]   2> 3232354 INFO  (jetty-launcher-3519-thread-1) 
[n:127.0.0.1:50934_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50934_solr
   [junit4]   2> 3232354 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.Overseer Overseer 
(id=98174699265523718-127.0.0.1:58577_solr-n_0000000000) starting
   [junit4]   2> 3232366 INFO  
(zkCallback-3531-thread-1-processing-n:127.0.0.1:58577_solr) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 3232366 INFO  
(zkCallback-3530-thread-1-processing-n:127.0.0.1:50934_solr) 
[n:127.0.0.1:50934_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 3232395 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58577_solr
   [junit4]   2> 3232396 INFO  
(zkCallback-3530-thread-1-processing-n:127.0.0.1:50934_solr) 
[n:127.0.0.1:50934_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 3232404 INFO  
(zkCallback-3531-thread-1-processing-n:127.0.0.1:58577_solr) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 3232482 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58577.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3232492 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58577.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3232492 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58577.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3232493 INFO  (jetty-launcher-3519-thread-2) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node2/.
   [junit4]   2> 3232691 INFO  (jetty-launcher-3519-thread-1) 
[n:127.0.0.1:50934_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50934.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3232699 INFO  (jetty-launcher-3519-thread-1) 
[n:127.0.0.1:50934_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50934.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3232699 INFO  (jetty-launcher-3519-thread-1) 
[n:127.0.0.1:50934_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50934.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3232701 INFO  (jetty-launcher-3519-thread-1) 
[n:127.0.0.1:50934_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node1/.
   [junit4]   2> 3232738 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 3232739 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58287/solr ready
   [junit4]   2> 3232780 INFO  
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[389D3A4D00E5B7D1]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
   [junit4]   2> 3232850 ERROR (qtp101931055-27733) [n:127.0.0.1:50934_solr    
] o.a.s.h.RequestHandlerBase java.lang.RuntimeException: cores is only allowed 
in 'cluster-policy'
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.Policy.lambda$null$1(Policy.java:107)
   [junit4]   2>        at 
java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:174)
   [junit4]   2>        at 
java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
   [junit4]   2>        at 
java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1374)
   [junit4]   2>        at 
java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481)
   [junit4]   2>        at 
java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
   [junit4]   2>        at 
java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708)
   [junit4]   2>        at 
java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
   [junit4]   2>        at 
java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.Policy.lambda$new$2(Policy.java:112)
   [junit4]   2>        at java.util.HashMap.forEach(HashMap.java:1288)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.Policy.<init>(Policy.java:102)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:275)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.zkSetPolicies(AutoScalingHandler.java:224)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleSetPolicies(AutoScalingHandler.java:205)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:104)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
   [junit4]   2>        at 
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2>        at 
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:312)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 3232850 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=500 QTime=30
   [junit4]   2> 3232851 ERROR (qtp101931055-27733) [n:127.0.0.1:50934_solr    
] o.a.s.s.HttpSolrCall null:java.lang.RuntimeException: cores is only allowed 
in 'cluster-policy'
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.Policy.lambda$null$1(Policy.java:107)
   [junit4]   2>        at 
java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:174)
   [junit4]   2>        at 
java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
   [junit4]   2>        at 
java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1374)
   [junit4]   2>        at 
java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481)
   [junit4]   2>        at 
java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
   [junit4]   2>        at 
java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708)
   [junit4]   2>        at 
java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
   [junit4]   2>        at 
java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.Policy.lambda$new$2(Policy.java:112)
   [junit4]   2>        at java.util.HashMap.forEach(HashMap.java:1288)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.Policy.<init>(Policy.java:102)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:275)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.zkSetPolicies(AutoScalingHandler.java:224)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleSetPolicies(AutoScalingHandler.java:205)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:104)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
   [junit4]   2>        at 
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2>        at 
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:312)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 3232852 INFO  
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[389D3A4D00E5B7D1]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=AutoScalingHandlerTest -Dtests.method=testPolicyAndPreferences 
-Dtests.seed=389D3A4D00E5B7D1 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
 -Dtests.locale=ar-BH -Dtests.timezone=Australia/NSW -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.11s J1 | AutoScalingHandlerTest.testPolicyAndPreferences 
<<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Adding a policy with 
'cores' attribute should not have succeeded.
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([389D3A4D00E5B7D1:9955646479A3B1DF]:0)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences(AutoScalingHandlerTest.java:83)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 3232890 INFO  
(TEST-AutoScalingHandlerTest.testReadApi-seed#[389D3A4D00E5B7D1]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReadApi
   [junit4]   2> 3232928 INFO  (qtp1960468712-27748) [n:127.0.0.1:58577_solr    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 3232930 INFO  (qtp1960468712-27748) [n:127.0.0.1:58577_solr    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58287/solr ready
   [junit4]   2> 3232945 INFO  (qtp1960468712-27744) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3232950 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 3232954 INFO  (qtp1960468712-27748) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=33
   [junit4]   2> 3232958 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 3232959 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58287/solr ready
   [junit4]   2> 3232962 INFO  (qtp1960468712-27746) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=2
   [junit4]   2> 3232965 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 3232967 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=11
   [junit4]   2> 3232971 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 3232972 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58287/solr ready
   [junit4]   2> 3232973 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 3232974 INFO  (qtp101931055-27735) [n:127.0.0.1:50934_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 3232975 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 3232976 INFO  (qtp1960468712-27742) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 3232979 INFO  (qtp1960468712-27748) [n:127.0.0.1:58577_solr    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 3232980 INFO  (qtp1960468712-27748) [n:127.0.0.1:58577_solr    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58287/solr ready
   [junit4]   2> 3232982 INFO  (qtp1960468712-27744) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 3232983 INFO  (qtp101931055-27741) [n:127.0.0.1:50934_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 3232984 INFO  (qtp1960468712-27748) [n:127.0.0.1:58577_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling/diagnostics 
params={wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 3232985 INFO  (qtp1960468712-27746) [n:127.0.0.1:58577_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
pullReplicas=0&replicationFactor=6&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3232991 INFO  
(OverseerThreadFactory-9188-thread-1-processing-n:127.0.0.1:58577_solr) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
readApiTestViolations
   [junit4]   2> 3232991 INFO  
(OverseerThreadFactory-9188-thread-1-processing-n:127.0.0.1:58577_solr) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.CreateCollectionCmd Only one config set 
found in zk - using it:conf
   [junit4]   2> 3232991 WARN  
(OverseerThreadFactory-9188-thread-1-processing-n:127.0.0.1:58577_solr) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.CreateCollectionCmd Specified number of 
replicas of 6 on collection readApiTestViolations is higher than the number of 
Solr instances currently live or live and part of your createNodeSet(2). It's 
unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 3233115 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n6&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3233115 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 3233117 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n5&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3233117 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 3233119 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n3&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3233123 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3233125 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n4&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3233128 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n2&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 3233269 INFO  
(zkCallback-3530-thread-1-processing-n:127.0.0.1:50934_solr) 
[n:127.0.0.1:50934_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 3233269 INFO  
(zkCallback-3531-thread-1-processing-n:127.0.0.1:58577_solr) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 3234144 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 3234145 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 3234151 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 3234153 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 3234179 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] 
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n4] Schema 
name=minimal
   [junit4]   2> 3234181 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 3234181 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n2] Schema 
name=minimal
   [junit4]   2> 3234183 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] 
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n6] Schema 
name=minimal
   [junit4]   2> 3234184 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 3234185 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3234185 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 
'readApiTestViolations_shard1_replica_n2' using configuration from collection 
readApiTestViolations, trusted=true
   [junit4]   2> 3234185 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50934.solr.core.readApiTestViolations.shard1.replica_n2' (registry 
'solr.core.readApiTestViolations.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3234186 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3234186 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n2] ] Opening new 
SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n2],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n2/data/]
   [junit4]   2> 3234186 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3234186 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] 
o.a.s.c.CoreContainer Creating SolrCore 
'readApiTestViolations_shard1_replica_n6' using configuration from collection 
readApiTestViolations, trusted=true
   [junit4]   2> 3234187 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50934.solr.core.readApiTestViolations.shard1.replica_n6' (registry 
'solr.core.readApiTestViolations.shard1.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3234187 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3234187 INFO  (qtp101931055-27745) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] 
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n6] ] Opening new 
SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n6],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n6/data/]
   [junit4]   2> 3234191 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n3] Schema 
name=minimal
   [junit4]   2> 3234191 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n5] Schema 
name=minimal
   [junit4]   2> 3234192 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3234192 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] 
o.a.s.c.CoreContainer Creating SolrCore 
'readApiTestViolations_shard1_replica_n4' using configuration from collection 
readApiTestViolations, trusted=true
   [junit4]   2> 3234192 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50934.solr.core.readApiTestViolations.shard1.replica_n4' (registry 
'solr.core.readApiTestViolations.shard1.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3234193 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3234193 INFO  (qtp101931055-27733) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] 
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n4] ] Opening new 
SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n4],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n4/data/]
   [junit4]   2> 3234194 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3234195 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 
o.a.s.c.CoreContainer Creating SolrCore 
'readApiTestViolations_shard1_replica_n3' using configuration from collection 
readApiTestViolations, trusted=true
   [junit4]   2> 3234195 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58577.solr.core.readApiTestViolations.shard1.replica_n3' (registry 
'solr.core.readApiTestViolations.shard1.replica_n3') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3234195 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3234195 INFO  (qtp1960468712-27734) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n3] ] Opening new 
SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n3],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n3/data/]
   [junit4]   2> 3234196 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3234196 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.c.CoreContainer Creating SolrCore 
'readApiTestViolations_shard1_replica_n5' using configuration from collection 
readApiTestViolations, trusted=true
   [junit4]   2> 3234197 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58577.solr.core.readApiTestViolations.shard1.replica_n5' (registry 
'solr.core.readApiTestViolations.shard1.replica_n5') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3234197 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3234197 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n5] ] Opening new 
SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n5],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n5/data/]
   [junit4]   2> 3234198 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n1] Schema 
name=minimal
   [junit4]   2> 3234201 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 3234201 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 
'readApiTestViolations_shard1_replica_n1' using configuration from collection 
readApiTestViolations, trusted=true
   [junit4]   2> 3234203 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_58577.solr.core.readApiTestViolations.shard1.replica_n1' (registry 
'solr.core.readApiTestViolations.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@13448615
   [junit4]   2> 3234203 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3234203 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n1] ] Opening new 
SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n1/data/]
   [junit4]   2> 3234320 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3234320 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3234326 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3234326 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3234328 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5813008b[readApiTestViolations_shard1_replica_n2] main]
   [junit4]   2> 3234334 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 3234337 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 3234341 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 3234342 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1570795190534275072
   [junit4]   2> 3234345 INFO  
(searcherExecutor-9193-thread-1-processing-n:127.0.0.1:50934_solr 
x:readApiTestViolations_shard1_replica_n2 s:shard1 c:readApiTestViolations) 
[n:127.0.0.1:50934_solr c:readApiTestViolations s:shard1  
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n2] Registered new searcher 
Searcher@5813008b[readApiTestViolations_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3234353 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3234353 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3234355 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3234355 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3234357 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@18fd235[readApiTestViolations_shard1_replica_n1] main]
   [junit4]   2> 3234360 INFO  (qtp101931055-27739) [n:127.0.0.1:50934_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=6 found=1 timeoutin=9998ms
   [junit4]   2> 3234361 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 3234361 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 3234362 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 3234365 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3234365 INFO  (qtp1960468712-27740) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1570795190558392320
   [junit4]   2> 3234365 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 3234366 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3234367 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3234368 INFO  
(searcherExecutor-9198-thread-1-processing-n:127.0.0.1:58577_solr 
x:readApiTestViolations_shard1_replica_n1 s:shard1 c:readApiTestViolations) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1  
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n1] Registered new searcher 
Searcher@18fd235[readApiTestViolations_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3234369 INFO  (qtp1960468712-27736) [n:127.0.0.1:58577_solr 
c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3e788238[readApiTestViolations_shard1_replica_n5]

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

it4]   2> 3236495 INFO  (coreCloseExecutor-9223-thread-1) 
[n:127.0.0.1:50934_solr c:readApiTestViolations s:shard1 r:core_node5 
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.readApiTestViolations.shard1.leader, tag=520446129
   [junit4]   2> 3236496 INFO  (coreCloseExecutor-9223-thread-2) 
[n:127.0.0.1:50934_solr c:readApiTestViolations s:shard1 r:core_node4 
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1943331541
   [junit4]   2> 3236496 INFO  (coreCloseExecutor-9224-thread-3) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1 r:core_node3 
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n3]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6bb597e8
   [junit4]   2> 3236498 INFO  (coreCloseExecutor-9224-thread-2) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n5] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.core.readApiTestViolations.shard1.replica_n5, tag=519357162
   [junit4]   2> 3236502 INFO  (coreCloseExecutor-9224-thread-2) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n5] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.readApiTestViolations.shard1.leader, tag=519357162
   [junit4]   2> 3236502 INFO  (coreCloseExecutor-9224-thread-1) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.core.readApiTestViolations.shard1.replica_n1, tag=1417576309
   [junit4]   2> 3236505 INFO  (coreCloseExecutor-9224-thread-1) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1417576309
   [junit4]   2> 3236506 INFO  (coreCloseExecutor-9224-thread-3) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1 r:core_node3 
x:readApiTestViolations_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.core.readApiTestViolations.shard1.replica_n3, tag=1807063016
   [junit4]   2> 3236509 INFO  (coreCloseExecutor-9224-thread-3) 
[n:127.0.0.1:58577_solr c:readApiTestViolations s:shard1 r:core_node3 
x:readApiTestViolations_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1807063016
   [junit4]   2> 3236514 INFO  (jetty-closer-3520-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=98174699265523718-127.0.0.1:58577_solr-n_0000000000) closing
   [junit4]   2> 3236515 INFO  
(OverseerStateUpdate-98174699265523718-127.0.0.1:58577_solr-n_0000000000) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:58577_solr
   [junit4]   2> 3238015 WARN  
(zkCallback-3530-thread-1-processing-n:127.0.0.1:50934_solr) 
[n:127.0.0.1:50934_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 3238016 WARN  
(zkCallback-3531-thread-1-processing-n:127.0.0.1:58577_solr) 
[n:127.0.0.1:58577_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 3238016 INFO  (jetty-closer-3520-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4fdbfa6e{/solr,null,UNAVAILABLE}
   [junit4]   2> 3238016 INFO  (jetty-closer-3520-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6f860149{/solr,null,UNAVAILABLE}
   [junit4]   2> 3238018 ERROR 
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 3238018 INFO  
(SUITE-AutoScalingHandlerTest-seed#[389D3A4D00E5B7D1]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:58287 58287
   [junit4]   2> 3238119 INFO  (Thread-10473) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:58287 58287
   [junit4]   2> 3238122 WARN  (Thread-10473) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        8       /solr/aliases.json
   [junit4]   2>        2       /solr/security.json
   [junit4]   2>        2       /solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        8       /solr/clusterstate.json
   [junit4]   2>        8       /solr/clusterprops.json
   [junit4]   2>        2       
/solr/collections/readApiTestViolations/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        8       /solr/live_nodes
   [junit4]   2>        8       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_389D3A4D00E5B7D1-001
   [junit4]   2> Jun 21, 2017 6:37:13 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=75, maxMBSortInHeap=7.913094376188193, 
sim=RandomSimilarity(queryNorm=true): {}, locale=ar-BH, timezone=Australia/NSW
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=111741960,total=513802240
   [junit4]   2> NOTE: All tests run in this JVM: [TestXmlQParser, 
TestBulkSchemaAPI, DocValuesTest, MetricsHandlerTest, 
TestPartialUpdateDeduplication, FileUtilsTest, DocValuesNotIndexedTest, 
TestCloudRecovery, AsyncCallRequestStatusResponseTest, HdfsThreadLeakTest, 
TestFilteredDocIdSet, DistributedQueryComponentOptimizationTest, 
TestCloudNestedDocsSort, SolrIndexMetricsTest, 
UpdateRequestProcessorFactoryTest, TestCursorMarkWithoutUniqueKey, TestSolrXml, 
OverseerCollectionConfigSetProcessorTest, SubstringBytesRefFilterTest, 
CdcrUpdateLogTest, SegmentsInfoRequestHandlerTest, 
AtomicUpdateProcessorFactoryTest, TestFuzzyAnalyzedSuggestions, 
BadComponentTest, DocExpirationUpdateProcessorFactoryTest, TestXIncludeConfig, 
TestSegmentSorting, TestSweetSpotSimilarityFactory, DeleteStatusTest, 
SpellCheckCollatorWithCollapseTest, BlockJoinFacetRandomTest, 
ClusterStateUpdateTest, TestFieldResource, AlternateDirectoryTest, 
TestEmbeddedSolrServerSchemaAPI, TestUseDocValuesAsStored, TestSolrJ, 
SliceStateTest, DistanceFunctionTest, TestPerFieldSimilarity, ZkCLITest, 
TestCharFilters, CustomCollectionTest, SolrInfoBeanTest, SparseHLLTest, 
TestElisionMultitermQuery, TestLegacyTerms, TestSearcherReuse, TestJsonFacets, 
QueryParsingTest, TestHashPartitioner, TestSurroundQueryParser, 
HdfsWriteToMultipleCollectionsTest, ManagedSchemaRoundRobinCloudTest, 
DistanceUnitsTest, OverseerStatusTest, DistributedFacetPivotLargeTest, 
TestRestoreCore, SchemaVersionSpecificBehaviorTest, TestFiltering, 
ReplicationFactorTest, TestInitQParser, TestFileDictionaryLookup, 
MissingSegmentRecoveryTest, TestTlogReplica, TestUpdate, TestPointFields, 
ClassificationUpdateProcessorIntegrationTest, CollectionReloadTest, 
TestRawTransformer, MergeStrategyTest, TestDynamicLoading, SOLR749Test, 
TestSimpleTrackingShardHandler, SolrCLIZkUtilsTest, 
TlogReplayBufferedWhileIndexingTest, TestStandardQParsers, SmileWriterTest, 
TestNamedUpdateProcessors, LoggingHandlerTest, TestStressRecovery, 
TestManagedResource, TestAddFieldRealTimeGet, TestRTGBase, TestConfigOverlay, 
SuggesterFSTTest, TestCloudJSONFacetJoinDomain, TestPullReplica, 
DistribJoinFromCollectionTest, TestSubQueryTransformer, TestSchemaNameResource, 
PreAnalyzedUpdateProcessorTest, TestNumericRangeQuery64, UtilsToolTest, 
DistributedFacetPivotSmallTest, TestScoreJoinQPScore, TestDefaultStatsCache, 
TestDistributedGrouping, DistributedDebugComponentTest, 
ChaosMonkeySafeLeaderTest, SpellCheckComponentTest, ZkStateReaderTest, 
BigEndianAscendingWordSerializerTest, TestQueryWrapperFilter, 
SpatialFilterTest, IndexBasedSpellCheckerTest, TestJavabinTupleStreamParser, 
DistributedIntervalFacetingTest, RuleEngineTest, V2ApiIntegrationTest, 
TestPostingsSolrHighlighter, TestFieldCacheSortRandom, 
DirectSolrConnectionTest, InfixSuggestersTest, 
TestStressCloudBlindAtomicUpdates, TestStressVersions, TestSystemIdResolver, 
TestComplexPhraseQParserPlugin, BasicDistributedZkTest, 
CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, 
ShardRoutingTest, RecoveryZkTest, TestReplicationHandler, 
CollectionsAPIAsyncDistributedZkTest, ConfigSetsAPITest, 
CreateCollectionCleanupTest, DeleteReplicaTest, ForceLeaderTest, 
RemoteQueryErrorTest, ReplaceNodeTest, SSLMigrationTest, SaslZkACLProviderTest, 
SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, 
SolrCloudExampleTest, SolrXmlInZkTest, TestAuthenticationFramework, 
TestCloudPivotFacet, TestCloudPseudoReturnFields, TestCollectionAPI, 
TestConfigSetsAPI, TestCryptoKeys, TestDeleteCollectionOnDownNodes, 
TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, 
TestLeaderElectionWithEmptyReplica, TestLeaderInitiatedRecoveryThread, 
TestLocalFSCloudBackupRestore, TestPullReplicaErrorHandling, 
TestReplicaProperties, TestRequestForwarding, 
TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, 
TestTolerantUpdateProcessorRandomCloud, AutoScalingHandlerTest]
   [junit4] Completed [559/725 (2!)] on J1 in 6.77s, 2 tests, 1 failure <<< 
FAILURES!

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

Reply via email to