lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build # 1018 - Still unstable!
Date Mon, 19 Dec 2016 14:01:29 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1018/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC

2 tests failed.
FAILED:  org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail

Error Message:
expected:<200> but was:<404>

Stack Trace:
java.lang.AssertionError: expected:<200> but was:<404>
	at __randomizedtesting.SeedInfo.seed([A0F7B576BF97074B:C848805C6F0D15A7]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140)
	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:294)
	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:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	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:367)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.update.AutoCommitTest.testMaxTime

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([A0F7B576BF97074B:3A03C894210D9B77]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
	at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
	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:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	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:367)
	at java.lang.Thread.run(Thread.java:745)
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">0</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:811)
	... 40 more




Build Log:
[...truncated 11691 lines...]
   [junit4] Suite: org.apache.solr.update.AutoCommitTest
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_A0F7B576BF97074B-001/init-core-data-001
   [junit4]   2> 1519357 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1519358 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1519358 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1519385 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1519405 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1520463 WARN  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1520465 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1520500 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1520528 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1520560 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1520686 WARN  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1520690 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1520700 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1520700 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_A0F7B576BF97074B-001/init-core-data-001/]
   [junit4]   2> 1520700 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15f8087d
   [junit4]   2> 1520701 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=542673514, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1520720 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1520720 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1520721 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1520721 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1520721 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1520721 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@51a7d18f[collection1] main]
   [junit4]   2> 1520722 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1520727 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1520748 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1520749 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1520749 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1520749 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1520749 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1520749 INFO  (coreLoadExecutor-7620-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1554150860787810304
   [junit4]   2> 1520750 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1520752 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1520753 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1520753 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1520753 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1520754 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1520754 INFO  (searcherExecutor-7621-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51a7d18f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1520757 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
   [junit4]   2> 1520759 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:* (-1554150860797247488)} 0 1
   [junit4]   2> 1520761 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1520798 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1520831 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1520963 WARN  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1520965 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1520974 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1520975 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_A0F7B576BF97074B-001/init-core-data-001/]
   [junit4]   2> 1520975 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15f8087d
   [junit4]   2> 1520993 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1520993 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1520994 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4cb64e0b[collection1] main]
   [junit4]   2> 1520994 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1520995 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1520999 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1521008 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=98.8984375, floorSegmentMB=0.5234375, forceMergeDeletesPctAllowed=6.731864457747826, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1521008 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1521008 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1521009 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1521009 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1521009 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1521009 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1521009 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1521010 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1521010 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1521010 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1521011 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4cb64e0b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1521011 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c53b3e0[collection1] main]
   [junit4]   2> 1521011 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4f3bade
   [junit4]   2> 1521012 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c53b3e0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1521022 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1554150861073022976)]} 0 1
   [junit4]   2> 1521023 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1554150861074071552)]} 0 1
   [junit4]   2> 1521023 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1554150861075120128)]} 0 2
   [junit4]   2> 1521023 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1554150861075120129)]} 0 2
   [junit4]   2> 1521024 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1554150861075120130)]} 0 2
   [junit4]   2> 1521024 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1554150861076168704)]} 0 2
   [junit4]   2> 1521024 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1554150861076168705)]} 0 3
   [junit4]   2> 1521024 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1554150861076168706)]} 0 3
   [junit4]   2> 1521024 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1554150861076168707)]} 0 3
   [junit4]   2> 1521024 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1554150861076168708)]} 0 3
   [junit4]   2> 1521025 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1554150861077217280)]} 0 3
   [junit4]   2> 1521025 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1554150861077217281)]} 0 3
   [junit4]   2> 1521025 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1554150861077217282)]} 0 3
   [junit4]   2> 1521025 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1554150861077217283)]} 0 4
   [junit4]   2> 1521025 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1521026 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1554150861078265856)]} 0 4
   [junit4]   2> 1521027 INFO  (commitScheduler-7629-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1521031 INFO  (commitScheduler-7629-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1df9f8a9[collection1] main]
   [junit4]   2> 1521031 INFO  (commitScheduler-7629-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1521032 INFO  (searcherExecutor-7626-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1df9f8a9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C15)))}
   [junit4]   2> 1521278 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1554150861341458432)]} 0 257
   [junit4]   2> 1521278 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 1521279 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1521279 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
   [junit4]   2> 1521284 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 1521287 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e2c0568[collection1] realtime]
   [junit4]   2> 1521287 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:* (-1554150861348798464)} 0 3
   [junit4]   2> 1521288 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1521327 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1521355 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1521488 WARN  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1521491 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1521502 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1521502 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_A0F7B576BF97074B-001/init-core-data-001/]
   [junit4]   2> 1521502 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15f8087d
   [junit4]   2> 1521528 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1521529 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1521529 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@52996fdb[collection1] main]
   [junit4]   2> 1521529 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1521530 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1521538 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1521545 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1521548 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1521548 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1521548 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1521550 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1521550 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=63.2587890625, floorSegmentMB=0.630859375, forceMergeDeletesPctAllowed=29.838008475031735, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4148482673190109
   [junit4]   2> 1521550 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1521551 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1521551 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1521552 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1521552 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1521554 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@52996fdb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1521554 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c382688[collection1] main]
   [junit4]   2> 1521554 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@39daef51
   [junit4]   2> 1521555 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c382688[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1521577 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1554150861654982656)]} 0 0
   [junit4]   2> 1521578 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    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> 1525576 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1525578 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@463069ab[collection1] main]
   [junit4]   2> 1525579 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1525579 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@463069ab[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 1525595 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1554150865868161024)]} 0 4020
   [junit4]   2> 1525595 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    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> 1525596 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1554150865870258176)]} 0 4022
   [junit4]   2> 1525596 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    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=1 status=0 QTime=0
   [junit4]   2> 1527597 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1527599 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1642011b[collection1] main]
   [junit4]   2> 1527599 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1527600 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1642011b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C1)))}
   [junit4]   2> 1527603 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    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> 1527604 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    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=1 status=0 QTime=0
   [junit4]   2> 1527604 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867975798784)]} 0 6031
   [junit4]   2> 1527605 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847360)]} 0 6031
   [junit4]   2> 1527605 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847361)]} 0 6032
   [junit4]   2> 1527605 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847362)]} 0 6032
   [junit4]   2> 1527605 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847363)]} 0 6032
   [junit4]   2> 1527605 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847364)]} 0 6032
   [junit4]   2> 1527605 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847365)]} 0 6032
   [junit4]   2> 1527605 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847366)]} 0 6032
   [junit4]   2> 1527606 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867976847367)]} 0 6032
   [junit4]   2> 1527606 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1554150867977895936)]} 0 6033
   [junit4]   2> 1527606 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1527606 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867977895937)]} 0 6033
   [junit4]   2> 1527606 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867977895938)]} 0 6033
   [junit4]   2> 1527606 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867977895939)]} 0 6033
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867978944512)]} 0 6033
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867978944513)]} 0 6033
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867978944514)]} 0 6034
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867978944515)]} 0 6034
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867978944516)]} 0 6034
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867978944517)]} 0 6034
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1554150867978944518)]} 0 6034
   [junit4]   2> 1527607 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    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=1 status=0 QTime=0
   [junit4]   2> 1528607 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1528609 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f438876[collection1] main]
   [junit4]   2> 1528609 INFO  (commitScheduler-7633-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1528610 INFO  (searcherExecutor-7630-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f438876[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C10/9:delGen=1)))}
   [junit4]   2> 1528611 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 1528612 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    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> 1528612 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> 1528614 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
   [junit4]   2> 1528615 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f5e1528[collection1] realtime]
   [junit4]   2> 1528615 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:* (-1554150869034860544)} 0 0
   [junit4]   2> 1528616 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1528646 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1528666 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1528762 WARN  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1528764 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1528774 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1528775 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_A0F7B576BF97074B-001/init-core-data-001/]
   [junit4]   2> 1528775 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15f8087d
   [junit4]   2> 1528789 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1528789 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1528790 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@18df2469[collection1] main]
   [junit4]   2> 1528790 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1528791 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1528794 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1528797 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=16.87109375, floorSegmentMB=1.080078125, forceMergeDeletesPctAllowed=12.446933977400473, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3348340789650662
   [junit4]   2> 1528797 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1528797 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1528797 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1528797 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1528797 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1528798 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1528798 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1528798 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1528798 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1528799 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1528800 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18df2469[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1528800 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dcf29b7[collection1] main]
   [junit4]   2> 1528800 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@66d8a5ed
   [junit4]   2> 1528809 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1554150869237235712)]} 0 1
   [junit4]   2> 1528951 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    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=142
   [junit4]   2> 1528952 INFO  (searcherExecutor-7634-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1dcf29b7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1529203 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1554150869652471808)]} 0 396
   [junit4]   2> 1529204 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    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> 1529204 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    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">0</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> 1529204 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    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">0</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:811)
   [junit4]   2> 	at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
   [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:367)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
   [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:367)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1529205 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[A0F7B576BF97074B]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=A0F7B576BF97074B -Dtests.slow=true -Dtests.locale=ru-RU -Dtests.timezone=America/Resolute -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.60s J1 | AutoCommitTest.testMaxTime <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([A0F7B576BF97074B:3A03C894210D9B77]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
   [junit4]    > 	at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [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">0</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:811)
   [junit4]    > 	... 40 more
   [junit4]   2> 1529208 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1529208 INFO  (SUITE-AutoCommitTest-seed#[A0F7B576BF97074B]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=322747286
   [junit4]   2> 1529208 INFO  (coreCloseExecutor-7638-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@14f49b6a
   [junit4]   2> 1529208 INFO  (coreCloseExecutor-7638-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1529209 INFO  (coreCloseExecutor-7638-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@63d3ee45
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest_A0F7B576BF97074B-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {range_facet_l_dv=FST50, _version_=PostingsFormat(name=MockRandom), field_t=FST50, multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), subject=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=PostingsFormat(name=MockRandom), id=FST50, range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), text=PostingsFormat(name=LuceneFixedGap), range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), timestamp=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1192, maxMBSortInHeap=6.6586024563649815, sim=RandomSimilarity(queryNorm=false): {}, locale=ru-RU, timezone=America/Resolute
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112 (64-bit)/cpus=3,threads=1,free=278575296,total=494927872
   [junit4]   2> NOTE: All tests run in this JVM: [TestLuceneMatchVersion, TestExportWriter, DistributedFacetExistsSmallTest, SignatureUpdateProcessorFactoryTest, CoreAdminHandlerTest, TestLeaderElectionZkExpiry, OverriddenZkACLAndCredentialsProvidersTest, BlockJoinFacetDistribTest, SuggestComponentTest, TestFieldCache, TestStressRecovery, SolrIndexSplitterTest, RulesTest, BitVectorTest, TestDefaultSearchFieldResource, TestValueSourceCache, AliasIntegrationTest, TestFieldCacheSortRandom, TestCollationFieldDocValues, TestReversedWildcardFilterFactory, TestUniqueKeyFieldResource, TestSearcherReuse, TestConfigSetsAPIZkFailure, TestReplicationHandlerBackup, TestCrossCoreJoin, RemoteQueryErrorTest, TestJoin, TestDocBasedVersionConstraints, TestStressUserVersions, ReplicationFactorTest, TestNumericTerms32, TestFreeTextSuggestions, DistributedFacetPivotSmallTest, TestSolrQueryParserDefaultOperatorResource, FieldMutatingUpdateProcessorTest, HighlighterMaxOffsetTest, CdcrReplicationHandlerTest, TestIndexingPerformance, TemplateUpdateProcessorTest, TestBadConfig, RequestHandlersTest, PreAnalyzedFieldManagedSchemaCloudTest, CollectionTooManyReplicasTest, DistanceUnitsTest, BadIndexSchemaTest, InfoHandlerTest, TestSchemaVersionResource, TestSolrConfigHandlerConcurrent, TestUtils, SpellingQueryConverterTest, TestBinaryResponseWriter, TestJmxMonitoredMap, CircularListTest, TestDistributedMissingSort, TestRestoreCore, ResourceLoaderTest, TestSSLRandomization, TestSolrQueryParser, TestLMDirichletSimilarityFactory, LeaderFailureAfterFreshStartTest, TestShardHandlerFactory, TestSolrDynamicMBean, TestCloudInspectUtil, IndexSchemaTest, TestFiltering, TestSolrIndexConfig, ConvertedLegacyTest, TestTestInjection, ConjunctionSolrSpellCheckerTest, TestFoldingMultitermQuery, TestDFISimilarityFactory, ParsingFieldUpdateProcessorsTest, TestSchemaResource, LoggingHandlerTest, TestInitQParser, TestConfigOverlay, ScriptEngineTest, TestScoreJoinQPNoScore, TermVectorComponentDistributedTest, TestLRUStatsCache, TestCloudPseudoReturnFields, FileBasedSpellCheckerTest, BlockCacheTest, TestQueryUtils, TestAtomicUpdateErrorCases, TestClusterProperties, DistributedDebugComponentTest, UpdateParamsTest, TestConfig, HighlighterConfigTest, DebugComponentTest, TestRecoveryHdfs, DefaultValueUpdateProcessorTest, HdfsUnloadDistributedZkTest, PeerSyncReplicationTest, TestRestManager, TestSolr4Spatial, NoCacheHeaderTest, AnalysisErrorHandlingTest, SpellCheckCollatorTest, TestZkChroot, SuggesterWFSTTest, DateRangeFieldTest, TestRuleBasedAuthorizationPlugin, ExternalFileFieldSortTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestNRTOpen, TestRTimerTree, SpatialHeatmapFacetsTest, StatsComponentTest, TestSearchPerf, TestTrie, HdfsBasicDistributedZk2Test, TestManagedResource, SolrCloudExampleTest, TestRandomFaceting, MultiThreadedOCPTest, TestSystemIdResolver, CollectionsAPIDistributedZkTest, CacheHeaderTest, MergeStrategyTest, SliceStateTest, QueryResultKeyTest, TestManagedSchemaAPI, DistributedVersionInfoTest, HLLUtilTest, ClassificationUpdateProcessorTest, ZkSolrClientTest, TestSolrCloudSnapshots, TestSolrCloudWithSecureImpersonation, CurrencyFieldOpenExchangeTest, TestDownShardTolerantSearch, RegexBoostProcessorTest, CdcrBootstrapTest, SpellCheckComponentTest, TestHdfsUpdateLog, TestFastWriter, RAMDirectoryFactoryTest, TestDocSet, ExitableDirectoryReaderTest, TestStressLiveNodes, TestPHPSerializedResponseWriter, TestTolerantUpdateProcessorRandomCloud, WordBreakSolrSpellCheckerTest, NotRequiredUniqueKeyTest, SimpleMLTQParserTest, TestChildDocTransformer, TestBulkSchemaConcurrent, ShowFileRequestHandlerTest, TriLevelCompositeIdRoutingTest, ReturnFieldsTest, TestFuzzyAnalyzedSuggestions, BufferStoreTest, CollectionReloadTest, OverseerStatusTest, TestRawTransformer, TestExactSharedStatsCache, BooleanFieldTest, ForceLeaderTest, SolrCmdDistributorTest, TestDynamicFieldResource, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, BasicZkTest, FullSolrCloudDistribCmdsTest, AutoCommitTest]
   [junit4] Completed [343/660 (1!)] on J1 in 9.88s, 3 tests, 1 error <<< FAILURES!

[...truncated 501 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_A0F7B576BF97074B-001/init-core-data-001
   [junit4]   2> 2181547 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2181551 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_A0F7B576BF97074B-001/tempDir-001
   [junit4]   2> 2181551 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2181552 INFO  (Thread-5059) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2181552 INFO  (Thread-5059) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2181653 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:51309
   [junit4]   2> 2181665 INFO  (jetty-launcher-3937-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2181666 INFO  (jetty-launcher-3937-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2181670 INFO  (jetty-launcher-3937-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d5c9fc8{/solr,null,AVAILABLE}
   [junit4]   2> 2181670 INFO  (jetty-launcher-3937-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@426c0c8d{/solr,null,AVAILABLE}
   [junit4]   2> 2181674 INFO  (jetty-launcher-3937-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5b427b8f{HTTP/1.1,[http/1.1]}{127.0.0.1:55551}
   [junit4]   2> 2181674 INFO  (jetty-launcher-3937-thread-1) [    ] o.e.j.s.Server Started @2185236ms
   [junit4]   2> 2181674 INFO  (jetty-launcher-3937-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55551}
   [junit4]   2> 2181675 ERROR (jetty-launcher-3937-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2181675 INFO  (jetty-launcher-3937-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 2181675 INFO  (jetty-launcher-3937-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2181675 INFO  (jetty-launcher-3937-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2181675 INFO  (jetty-launcher-3937-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-12-19T13:33:40.524Z
   [junit4]   2> 2181677 INFO  (jetty-launcher-3937-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@64316a3e{HTTP/1.1,[http/1.1]}{127.0.0.1:34528}
   [junit4]   2> 2181677 INFO  (jetty-launcher-3937-thread-2) [    ] o.e.j.s.Server Started @2185239ms
   [junit4]   2> 2181677 INFO  (jetty-launcher-3937-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34528}
   [junit4]   2> 2181677 ERROR (jetty-launcher-3937-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2181677 INFO  (jetty-launcher-3937-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 2181678 INFO  (jetty-launcher-3937-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2181678 INFO  (jetty-launcher-3937-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2181678 INFO  (jetty-launcher-3937-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-12-19T13:33:40.527Z
   [junit4]   2> 2181680 INFO  (jetty-launcher-3937-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2181682 INFO  (jetty-launcher-3937-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2181694 INFO  (jetty-launcher-3937-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51309/solr
   [junit4]   2> 2181694 INFO  (jetty-launcher-3937-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51309/solr
   [junit4]   2> 2181760 INFO  (jetty-launcher-3937-thread-1) [n:127.0.0.1:55551_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55551_solr
   [junit4]   2> 2181760 INFO  (jetty-launcher-3937-thread-2) [n:127.0.0.1:34528_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34528_solr
   [junit4]   2> 2181764 INFO  (jetty-launcher-3937-thread-1) [n:127.0.0.1:55551_solr    ] o.a.s.c.Overseer Overseer (id=97134472095531014-127.0.0.1:55551_solr-n_0000000000) starting
   [junit4]   2> 2181765 INFO  (zkCallback-3948-thread-1-processing-n:127.0.0.1:34528_solr) [n:127.0.0.1:34528_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2181766 INFO  (zkCallback-3947-thread-1-processing-n:127.0.0.1:55551_solr) [n:127.0.0.1:55551_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2181836 INFO  (jetty-launcher-3937-thread-1) [n:127.0.0.1:55551_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55551_solr
   [junit4]   2> 2181840 INFO  (zkCallback-3948-thread-1-processing-n:127.0.0.1:34528_solr) [n:127.0.0.1:34528_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2181840 INFO  (zkCallback-3947-thread-1-processing-n:127.0.0.1:55551_solr) [n:127.0.0.1:55551_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2181934 INFO  (jetty-launcher-3937-thread-1) [n:127.0.0.1:55551_solr    ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
   [junit4]   2> 2181954 INFO  (jetty-launcher-3937-thread-2) [n:127.0.0.1:34528_solr    ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
   [junit4]   2> 2181966 INFO  (jetty-launcher-3937-thread-1) [n:127.0.0.1:55551_solr    ] o.a.s.s.KerberosPlugin Params: {token.valid=30, delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, token.validity=36000, signer.secret.provider.zookeeper.path=/token, type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper}
   [junit4]   2> 2181974 INFO  (jetty-launcher-3937-thread-2) [n:127.0.0.1:34528_solr    ] o.a.s.s.KerberosPlugin Params: {token.valid=30, delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, token.validity=36000, signer.secret.provider.zookeeper.path=/token, type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper}
   [junit4]   2> 2182139 INFO  (jetty-launcher-3937-thread-2) [n:127.0.0.1:34528_solr    ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 2182139 INFO  (jetty-launcher-3937-thread-1) [n:127.0.0.1:55551_solr    ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 2182191 INFO  (jetty-launcher-3937-thread-1-EventThread) [n:127.0.0.1:55551_solr    ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 2182191 INFO  (jetty-launcher-3937-thread-2-EventThread) [n:127.0.0.1:34528_solr    ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 2182426 INFO  (jetty-launcher-3937-thread-2) [n:127.0.0.1:34528_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_A0F7B576BF97074B-001/tempDir-001/node2/.
   [junit4]   2> 2182431 INFO  (jetty-launcher-3937-thread-1) [n:127.0.0.1:55551_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_A0F7B576BF97074B-001/tempDir-001/node1/.
   [junit4]   2> 2182470 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x1591749a8b30009, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2182544 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[A0F7B576BF97074B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
   [junit4]   2> 2182711 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[A0F7B576BF97074B]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.method=testDelegationTokenCancelFail -Dtests.seed=A0F7B576BF97074B -Dtests.slow=true -Dtests.locale=cs-CZ -Dtests.timezone=America/Atka -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.24s J0 | TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<200> but was:<404>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([A0F7B576BF97074B:C848805C6F0D15A7]:0)
   [junit4]    > 	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140)
   [junit4]    > 	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:294)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2182772 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
   [junit4]   2> 2182809 ERROR (qtp451555403-22731) [    ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
   [junit4]   2> 2182826 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2182829 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2182848 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2182854 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2182879 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2182881 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2182884 ERROR (qtp1053127048-22740) [    ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
   [junit4]   2> 2182895 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2182903 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2182905 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2182907 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2182909 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A0F7B576BF97074B]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
   [junit4]   2> 2182957 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
   [junit4]   2> 2182986 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2182988 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2183007 INFO  (qtp1053127048-22737) [n:127.0.0.1:55551_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa4wigFZO1YyMAQDFEfJP3AmXOMVFa4Zw9XpWSUyvZ6YB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2183007 INFO  (qtp1053127048-22737) [n:127.0.0.1:55551_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa4wigFZO1YyMAQDFEfJP3AmXOMVFa4Zw9XpWSUyvZ6YB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2183016 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2183019 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2183021 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2183027 INFO  (qtp1053127048-22740) [n:127.0.0.1:55551_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa4wigFZO1YyMAQDFEfJP3AmXOMVFa4Zw9XpWSUyvZ6YB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2183027 INFO  (qtp1053127048-22740) [n:127.0.0.1:55551_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa4wigFZO1YyMAQDFEfJP3AmXOMVFa4Zw9XpWSUyvZ6YB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2183034 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2183038 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2183042 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2183100 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2183102 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2183104 INFO  (qtp1053127048-22730) [n:127.0.0.1:55551_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa6HigFZO1YyhwUDFEQEp4Q1x6_wRKLQ04IzCGjbwErIB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2183104 INFO  (qtp1053127048-22730) [n:127.0.0.1:55551_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa6HigFZO1YyhwUDFEQEp4Q1x6_wRKLQ04IzCGjbwErIB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2183111 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2183115 INFO  (qtp1053127048-22736) [n:127.0.0.1:55551_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa6HigFZO1YyhwUDFEQEp4Q1x6_wRKLQ04IzCGjbwErIB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2183115 INFO  (qtp1053127048-22736) [n:127.0.0.1:55551_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa6HigFZO1YyhwUDFEQEp4Q1x6_wRKLQ04IzCGjbwErIB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2183119 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2183136 INFO  (qtp451555403-22742) [n:127.0.0.1:34528_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa7SigFZO1Yy0gYDFBS1mmFMrKDTENV8nAddYzHBFjF-B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2183136 INFO  (qtp451555403-22742) [n:127.0.0.1:34528_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa7SigFZO1Yy0gYDFBS1mmFMrKDTENV8nAddYzHBFjF-B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2183144 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2183147 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2183149 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2183155 INFO  (qtp1053127048-22737) [n:127.0.0.1:55551_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa7SigFZO1Yy0gYDFBS1mmFMrKDTENV8nAddYzHBFjF-B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2183155 INFO  (qtp1053127048-22737) [n:127.0.0.1:55551_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa7SigFZO1Yy0gYDFBS1mmFMrKDTENV8nAddYzHBFjF-B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2183160 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
   [junit4]   2> 2183183 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2183186 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51309/solr ready
   [junit4]   2> 2183188 INFO  (qtp1053127048-22730) [n:127.0.0.1:55551_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa76igFZO1Yy-gcDFPVxgMQXSDSQI6LaoKZlAHRsbJl_B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2183189 INFO  (qtp1053127048-22730) [n:127.0.0.1:55551_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkXSa76igFZO1Yy-gcDFPVxgMQXSDSQI6LaoKZlAHRsbJl_B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2183199 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A0F7B576BF97074B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [

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

ChannelImpl.java:235)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:188)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2185357 INFO  (Thread-5059) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51309 51309
   [junit4]   2> 2185359 WARN  (Thread-5059) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	16	/solr/aliases.json
   [junit4]   2> 	16	/solr/clusterprops.json
   [junit4]   2> 	2	/solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	16	/solr/clusterstate.json
   [junit4]   2> 	4	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_9
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_3
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_12
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_11
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	16	/solr/live_nodes
   [junit4]   2> 	16	/solr/collections
   [junit4]   2> 	2	/solr/overseer/queue
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
   [junit4]   2> 	2	/solr/overseer/queue-work
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
   [junit4]   2> 
   [junit4]   2> 2185360 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[A0F7B576BF97074B]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_A0F7B576BF97074B-001
   [junit4]   2> Dec 19, 2016 1:33:44 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=3, maxDocsPerChunk=3, blockSize=238), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=3, blockSize=238)), sim=RandomSimilarity(queryNorm=false): {}, locale=cs-CZ, timezone=America/Atka
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112 (64-bit)/cpus=3,threads=1,free=166678664,total=520093696
   [junit4]   2> NOTE: All tests run in this JVM: [ActionThrottleTest, SuggestComponentContextFilterQueryTest, TestInitParams, PrimitiveFieldTypeTest, TestTolerantSearch, BaseCdcrDistributedZkTest, StatsReloadRaceTest, TestScoreJoinQPScore, XmlUpdateRequestHandlerTest, TestBM25SimilarityFactory, TestSort, HdfsTlogReplayBufferedWhileIndexingTest, TestStressCloudBlindAtomicUpdates, PathHierarchyTokenizerFactoryTest, QueryEqualityTest, ZkStateWriterTest, TestNumericTerms64, TestLFUCache, RequiredFieldsTest, TestSolrJ, TestRequestStatusCollectionAPI, TestRandomDVFaceting, TestSolrXml, TestCSVResponseWriter, TestCloudRecovery, TestFieldCacheVsDocValues, TestOverriddenPrefixQueryForCustomFieldType, ZkNodePropsTest, TestLeaderInitiatedRecoveryThread, MultiTermTest, TestSizeLimitedDistributedMap, TestUseDocValuesAsStored, TestJettySolrRunner, UtilsToolTest, SpatialFilterTest, FileUtilsTest, ChangedSchemaMergeTest, MoreLikeThisHandlerTest, StandardRequestHandlerTest, TestSchemaSimilarityResource, TestPartialUpdateDeduplication, TestExtendedDismaxParser, TestJmxIntegration, TestSerializedLuceneMatchVersion, TestCopyFieldCollectionResource, TestExactStatsCache, TestSolrQueryParserResource, CachingDirectoryFactoryTest, UnloadDistributedZkTest, TestSubQueryTransformerDistrib, CursorPagingTest, TestHashPartitioner, TestFunctionQuery, TestMacros, TestDistribDocBasedVersion, TestOrdValues, TestGroupingSearch, TestReplicationHandler, TestCoreContainer, TestHdfsBackupRestoreCore, PolyFieldTest, TestRemoteStreaming, TestCloudSchemaless, TestCollapseQParserPlugin, TestDocumentBuilder, TestManagedSchema, CloudMLTQParserTest, BigEndianAscendingWordSerializerTest, SchemaVersionSpecificBehaviorTest, DocValuesMissingTest, TestBinaryField, TestMissingGroups, PrimUtilsTest, UUIDUpdateProcessorFallbackTest, TestReloadAndDeleteDocs, GraphQueryTest, TestSolrConfigHandlerCloud, TestCursorMarkWithoutUniqueKey, StatelessScriptUpdateProcessorFactoryTest, TestImplicitCoreProperties, RecoveryZkTest, ClassificationUpdateProcessorIntegrationTest, PeerSyncWithIndexFingerprintCachingTest, SolrCoreTest, TestLeaderElectionWithEmptyReplica, HighlighterTest, TlogReplayBufferedWhileIndexingTest, TestFieldCacheReopen, TestFieldCacheSort, AssignTest, DistributedQueryElevationComponentTest, TestLockTree, XsltUpdateRequestHandlerTest, TestFastOutputStream, SolrCLIZkUtilsTest, TestPivotHelperCode, DocumentBuilderTest, LeaderInitiatedRecoveryOnCommitTest, TestReplicaProperties, TimeZoneUtilsTest, TestSha256AuthenticationProvider, TestIntervalFaceting, TestRandomCollapseQParserPlugin, TestSweetSpotSimilarityFactory, JavabinLoaderTest, ConcurrentDeleteAndCreateCollectionTest, CollectionsAPIAsyncDistributedZkTest, TestSolrCoreSnapshots, DocValuesNotIndexedTest, AtomicUpdatesTest, TestReRankQParserPlugin, OutputWriterTest, CdcrUpdateLogTest, HLLSerializationTest, OverseerCollectionConfigSetProcessorTest, DistributedFacetPivotLargeTest, CloudExitableDirectoryReaderTest, DisMaxRequestHandlerTest, ManagedSchemaRoundRobinCloudTest, SecurityConfHandlerTest, BJQParserTest, BlobRepositoryCloudTest, TestExclusionRuleCollectionAccess, TestBackupRepositoryFactory, OverseerTest, LeaderFailoverAfterPartitionTest, SOLR749Test, TestCloudDeleteByQuery, PingRequestHandlerTest, TestElisionMultitermQuery, FastVectorHighlighterTest, ClusterStateUpdateTest, TestAddFieldRealTimeGet, TestExceedMaxTermLength, TestCustomStream, TestLazyCores, ShardSplitTest, TestPKIAuthenticationPlugin, BasicAuthIntegrationTest, DistributedExpandComponentTest, CdcrReplicationDistributedZkTest, ShardRoutingTest, PluginInfoTest, TestFaceting, TestOnReconnectListenerSupport, SoftAutoCommitTest, SolrTestCaseJ4Test, TestClassicSimilarityFactory, TestBlobHandler, TestSolrCLIRunExample, CustomCollectionTest, TestPerFieldSimilarity, ResponseHeaderTest, TestFastLRUCache, DistributedQueryComponentOptimizationTest, TestSurroundQueryParser, TestObjectReleaseTracker, TestXmlQParserPlugin, DistributedQueueTest, TestSubQueryTransformer, LeaderElectionTest, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, TestStressReorder, HardAutoCommitTest, TestRangeQuery, QueryElevationComponentTest, PeerSyncTest, CurrencyFieldXmlFileTest, SimplePostToolTest, TestCoreDiscovery, SuggesterFSTTest, SolrRequestParserTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, TestCSVLoader, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions, TermsComponentTest, TermVectorComponentTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, TestCollationField, UpdateRequestProcessorFactoryTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TestComponentsName, TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, SampleTest, NumericFieldsTest, MinimalSchemaTest, TestSolrCoreProperties, TestPhraseSuggestions, TestCodecSupport, TestDFRSimilarityFactory, TestIBSimilarityFactory, OpenExchangeRatesOrgProviderTest, DateMathParserTest, PreAnalyzedFieldTest, TestSuggestSpellingConverter, DateFieldTest, DOMUtilTest, ClusterStateTest, TestLRUCache, SystemInfoHandlerTest, UUIDFieldTest, DistributedMLTComponentTest, TestRTGBase, DistributedIntervalFacetingTest, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestEmbeddedSolrServerConstructors, ConnectionReuseTest, AsyncCallRequestStatusResponseTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, DeleteReplicaTest, DeleteStatusTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, HttpPartitionTest, ReplaceNodeTest, RestartWhileUpdatingTest, RollingRestartTest, SolrXmlInZkTest, TestCloudPivotFacet, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestCryptoKeys, TestLocalFSCloudBackupRestore, TestMiniSolrCloudCluster, TestRandomRequestDistribution, TestRequestForwarding, TestSegmentSorting, TestSolrCloudWithDelegationTokens]
   [junit4] Completed [508/660 (2!)] on J0 in 4.85s, 8 tests, 1 failure <<< FAILURES!

[...truncated 62885 lines...]


Mime
View raw message