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 # 1047 - Still Unstable!
Date Sun, 01 Jan 2017 03:21:32 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1047/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsRecoveryZkTest

Error Message:
ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException  at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)  at org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130)  at org.apache.solr.update.HdfsUpdateLog.init(HdfsUpdateLog.java:202)  at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:137)  at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:94)  at org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:102)  at sun.reflect.GeneratedConstructorAccessor139.newInstance(Unknown Source)  at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)  at java.lang.reflect.Constructor.newInstance(Constructor.java:423)  at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:747)  at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:809)  at org.apache.solr.core.SolrCore.initUpdateHandler(SolrCore.java:1060)  at org.apache.solr.core.SolrCore.<init>(SolrCore.java:925)  at org.apache.solr.core.SolrCore.<init>(SolrCore.java:817)  at org.apache.solr.core.CoreContainer.create(CoreContainer.java:906)  at org.apache.solr.core.CoreContainer.lambda$load$3(CoreContainer.java:551)  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)  at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)  at java.lang.Thread.run(Thread.java:745)  

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
	at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
	at org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130)
	at org.apache.solr.update.HdfsUpdateLog.init(HdfsUpdateLog.java:202)
	at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:137)
	at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:94)
	at org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:102)
	at sun.reflect.GeneratedConstructorAccessor139.newInstance(Unknown Source)
	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
	at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
	at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:747)
	at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:809)
	at org.apache.solr.core.SolrCore.initUpdateHandler(SolrCore.java:1060)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:925)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:817)
	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:906)
	at org.apache.solr.core.CoreContainer.lambda$load$3(CoreContainer.java:551)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)


	at __randomizedtesting.SeedInfo.seed([E3B52153CC2CEEE2]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertNull(Assert.java:551)
	at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:266)
	at sun.reflect.GeneratedMethodAccessor28.invoke(Unknown Source)
	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$7.evaluate(RandomizedRunner.java:870)
	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.testCommitWithin

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([E3B52153CC2CEEE2:59674E2B4F0200F7]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
	at org.apache.solr.update.AutoCommitTest.testCommitWithin(AutoCommitTest.java:353)
	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 11761 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_E3B52153CC2CEEE2-001/init-core-data-001
   [junit4]   2> 1507250 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1507250 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1507251 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-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> 1507287 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1507313 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1507432 WARN  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1507435 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1507453 WARN  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1507457 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1507458 WARN  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1507464 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1507464 WARN  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1507464 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1507464 WARN  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1507464 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1507466 INFO  (coreLoadExecutor-5661-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> 1507509 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1507529 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1507643 WARN  (coreLoadExecutor-5661-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> 1507645 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1507652 INFO  (coreLoadExecutor-5661-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> 1507652 INFO  (coreLoadExecutor-5661-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_E3B52153CC2CEEE2-001/init-core-data-001/]
   [junit4]   2> 1507653 WARN  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1507653 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1507653 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1507654 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1225865721, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1507693 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1507694 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1507695 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1507695 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1507695 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=91.986328125, floorSegmentMB=1.4013671875, forceMergeDeletesPctAllowed=29.6784936963833, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1507696 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ed28667[collection1] main]
   [junit4]   2> 1507697 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1508137 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1508189 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1508189 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1508190 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1508190 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1508190 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1508190 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1508191 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1508192 INFO  (coreLoadExecutor-5661-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1555288412173369344
   [junit4]   2> 1508192 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1508192 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1508193 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1508193 INFO  (searcherExecutor-5662-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7ed28667[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1508196 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1508200 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
   [junit4]   2> 1508202 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:* (-1555288412181757952)} 0 1
   [junit4]   2> 1508204 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] 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> 1508244 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1508293 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1508405 WARN  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1508407 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1508414 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    ] 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> 1508415 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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_E3B52153CC2CEEE2-001/init-core-data-001/]
   [junit4]   2> 1508415 WARN  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1508422 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1508423 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.SolrMetricManager Replacing existing reporter 'default' in registry 'solr.core.collection1': [org.apache.solr.metrics.reporters.SolrJmxReporter@7b7dac55: domain = solr.core.collection1, service url = null, agent id = null]
   [junit4]   2> 1508427 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1508448 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1508448 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1508448 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27e31a26[collection1] main]
   [junit4]   2> 1508448 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1508449 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1508452 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1508454 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=45.638671875, floorSegmentMB=1.353515625, forceMergeDeletesPctAllowed=0.2875045152300548, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2586174436932398
   [junit4]   2> 1508455 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1508455 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1508456 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1508456 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1508456 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1508456 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1508456 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1508457 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1508457 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1508457 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1508458 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27e31a26[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1508458 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@366f67c8[collection1] main]
   [junit4]   2> 1508458 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@ae8501d
   [junit4]   2> 1508459 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@366f67c8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1508464 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1508472 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1555288412464873472)]} 0 2
   [junit4]   2> 1508472 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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> 1509972 INFO  (commitScheduler-5670-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> 1509978 INFO  (commitScheduler-5670-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@23db41c8[collection1] main]
   [junit4]   2> 1509978 INFO  (commitScheduler-5670-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1509979 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@23db41c8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 1510227 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1555288414306172928)]} 0 1758
   [junit4]   2> 1510227 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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> 1510228 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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> 1510229 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{delete=[529 (-1555288414309318656)]} 0 0
   [junit4]   2> 1510229 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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> 1511727 INFO  (commitScheduler-5670-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> 1511730 INFO  (commitScheduler-5670-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@25ad0cf[collection1] main]
   [junit4]   2> 1511730 INFO  (commitScheduler-5670-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1511732 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@25ad0cf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):c1)))}
   [junit4]   2> 1511736 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[550 (1555288415889522688)]} 0 3268
   [junit4]   2> 1511737 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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> 1511737 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415890571264)]} 0 3269
   [junit4]   2> 1511737 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415890571265)]} 0 3269
   [junit4]   2> 1511738 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415891619840)]} 0 3269
   [junit4]   2> 1511738 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415891619841)]} 0 3269
   [junit4]   2> 1511738 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415891619842)]} 0 3270
   [junit4]   2> 1511738 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415891619843)]} 0 3270
   [junit4]   2> 1511738 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415891619844)]} 0 3270
   [junit4]   2> 1511739 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415892668416)]} 0 3270
   [junit4]   2> 1511739 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415892668417)]} 0 3270
   [junit4]   2> 1511739 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1555288415892668418)]} 0 3271
   [junit4]   2> 1511739 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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> 1513237 INFO  (commitScheduler-5670-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> 1513241 INFO  (commitScheduler-5670-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@586048ba[collection1] main]
   [junit4]   2> 1513241 INFO  (commitScheduler-5670-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1513243 INFO  (searcherExecutor-5667-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@586048ba[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c11/9:delGen=1)))}
   [junit4]   2> 1513243 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[531 (1555288417469726720)]} 0 4776
   [junit4]   2> 1513244 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    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> 1513244 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
   [junit4]   2> 1513249 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
   [junit4]   2> 1513253 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ff4a2de[collection1] realtime]
   [junit4]   2> 1513253 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:* (-1555288417476018176)} 0 4
   [junit4]   2> 1513254 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    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> 1513288 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1513309 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1513411 WARN  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    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> 1513413 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1513423 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    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> 1513424 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    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_E3B52153CC2CEEE2-001/init-core-data-001/]
   [junit4]   2> 1513424 WARN  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1513433 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1513433 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1513460 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1513460 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1513461 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f4be4a0[collection1] main]
   [junit4]   2> 1513461 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1513464 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1513468 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1513469 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1513469 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1513469 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1513469 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1513470 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1513470 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1513471 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1513472 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1513472 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1513473 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f4be4a0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1513479 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=17.9853515625, floorSegmentMB=2.125, forceMergeDeletesPctAllowed=3.6062889853270272, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1513479 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1513479 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12d2ec37[collection1] main]
   [junit4]   2> 1513480 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@617a6dfa
   [junit4]   2> 1513481 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12d2ec37[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1513489 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1513494 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1555288417731870720)]} 0 1
   [junit4]   2> 1513494 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1555288417732919296)]} 0 1
   [junit4]   2> 1513494 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1555288417732919297)]} 0 1
   [junit4]   2> 1513495 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1555288417733967872)]} 0 1
   [junit4]   2> 1513495 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1555288417733967873)]} 0 1
   [junit4]   2> 1513495 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1555288417733967874)]} 0 2
   [junit4]   2> 1513495 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1555288417733967875)]} 0 2
   [junit4]   2> 1513495 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1555288417733967876)]} 0 2
   [junit4]   2> 1513495 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1555288417733967877)]} 0 2
   [junit4]   2> 1513496 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1555288417733967878)]} 0 2
   [junit4]   2> 1513496 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1555288417735016448)]} 0 2
   [junit4]   2> 1513496 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1555288417735016449)]} 0 2
   [junit4]   2> 1513496 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1555288417735016450)]} 0 3
   [junit4]   2> 1513496 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1555288417735016451)]} 0 3
   [junit4]   2> 1513496 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    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> 1513497 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1555288417736065024)]} 0 3
   [junit4]   2> 1513498 INFO  (commitScheduler-5674-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> 1513507 INFO  (commitScheduler-5674-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12431c49[collection1] main]
   [junit4]   2> 1513507 INFO  (commitScheduler-5674-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1513508 INFO  (searcherExecutor-5671-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12431c49[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):c15)))}
   [junit4]   2> 1513749 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1555288417999257600)]} 0 255
   [junit4]   2> 1513749 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    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> 1513749 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    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> 1513749 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
   [junit4]   2> 1513754 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 1513760 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@209ef354[collection1] realtime]
   [junit4]   2> 1513760 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:* (-1555288418005549056)} 0 6
   [junit4]   2> 1513761 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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> 1513806 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1513861 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1513962 WARN  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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> 1513964 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1513972 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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> 1513972 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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_E3B52153CC2CEEE2-001/init-core-data-001/]
   [junit4]   2> 1513972 WARN  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 1513980 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1513980 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@299d5ce0
   [junit4]   2> 1514004 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1514005 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1514005 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@90f0e6b[collection1] main]
   [junit4]   2> 1514005 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1514006 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1514009 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1514013 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=11.5966796875, floorSegmentMB=2.1923828125, forceMergeDeletesPctAllowed=29.084769565545418, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1514013 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1514013 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1514013 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1514014 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1514014 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1514014 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1514014 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1514014 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1514015 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1514015 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1514016 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@90f0e6b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1514016 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@690f3e84[collection1] main]
   [junit4]   2> 1514017 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5530d082
   [junit4]   2> 1514023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1514028 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1555288418291810304)]} 0 1
   [junit4]   2> 1514028 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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> 1514029 INFO  (searcherExecutor-5675-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@690f3e84[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1514280 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1555288418556051456)]} 0 253
   [junit4]   2> 1514280 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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> 1514281 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1555288418558148608)]} 0 254
   [junit4]   2> 1514281 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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> 1514281 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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> 1514282 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    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.testCommitWithin(AutoCommitTest.java:353)
   [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> 1514282 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[E3B52153CC2CEEE2]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest -Dtests.method=testCommitWithin -Dtests.seed=E3B52153CC2CEEE2 -Dtests.slow=true -Dtests.locale=sr-Latn -Dtests.timezone=AST -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.54s J1 | AutoCommitTest.testCommitWithin <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([E3B52153CC2CEEE2:59674E2B4F0200F7]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
   [junit4]    > 	at org.apache.solr.update.AutoCommitTest.testCommitWithin(AutoCommitTest.java:353)
   [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> 1514285 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1514286 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1847411601
   [junit4]   2> 1514286 INFO  (coreCloseExecutor-5679-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1527d8cf
   [junit4]   2> 1514287 INFO  (coreCloseExecutor-5679-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1514287 INFO  (coreCloseExecutor-5679-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@59e438ec
   [junit4]   2> 1514306 INFO  (coreCloseExecutor-5679-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1514307 INFO  (SUITE-AutoCommitTest-seed#[E3B52153CC2CEEE2]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [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_E3B52153CC2CEEE2-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _version_=PostingsFormat(name=Asserting), field_t=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=MockRandom), subject=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=Asserting), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=PostingsFormat(name=Asserting), text=PostingsFormat(name=Direct), range_facet_l=PostingsFormat(name=Asserting), timestamp=PostingsFormat(name=Asserting)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1466, maxMBSortInHeap=7.179972059290144, sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Latn, timezone=AST
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112 (64-bit)/cpus=3,threads=1,free=167676376,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [TestJmxMonitoredMap, QueryResultKeyTest, BlockJoinFacetSimpleTest, TestDocTermOrds, SolrCloudExampleTest, TestManagedResource, TestLRUStatsCache, BlockDirectoryTest, TestRebalanceLeaders, TestOmitPositions, TestCorePropertiesReload, HighlighterConfigTest, OverseerModifyCollectionTest, TestIndexSearcher, UUIDFieldTest, CleanupOldIndexTest, TestCloudInspectUtil, DistributedVersionInfoTest, BadComponentTest, TestMinMaxOnMultiValuedField, TestSubQueryTransformerCrossCore, TestCloudPseudoReturnFields, TestInitQParser, TestDefaultSearchFieldResource, ZkStateReaderTest, CircularListTest, ZkSolrClientTest, TestZkChroot, JSONWriterTest, TestSSLRandomization, TestSearcherReuse, TestRangeQuery, DistanceFunctionTest, TestFileDictionaryLookup, RemoteQueryErrorTest, TestMacroExpander, MultiThreadedOCPTest, CoreAdminRequestStatusTest, TestAuthenticationFramework, ClassificationUpdateProcessorIntegrationTest, TestRecoveryHdfs, TestSolrIndexConfig, TestComponentsName, TestDistributedGrouping, TestDeleteCollectionOnDownNodes, TestSolrQueryParserDefaultOperatorResource, InfoHandlerTest, UUIDUpdateProcessorFallbackTest, TestFieldCacheSort, FieldMutatingUpdateProcessorTest, TestConfigReload, TestIndexingPerformance, TestCollationFieldDocValues, TestDocSet, CollectionReloadTest, TestBadConfig, TestFieldTypeResource, TestSolrQueryParser, SparseHLLTest, TestJoin, TestPushWriter, ResponseLogComponentTest, TestSolrCoreProperties, SolrJmxReporterTest, TestAtomicUpdateErrorCases, TestRuleBasedAuthorizationPlugin, CollectionTooManyReplicasTest, HdfsDirectoryTest, TestRestManager, SortSpecParsingTest, MergeStrategyTest, ReplicationFactorTest, TestSmileRequest, TestSchemaVersionResource, TestPerFieldSimilarityWithDefaultOverride, TriLevelCompositeIdRoutingTest, SpellCheckCollatorTest, TestFieldResource, HdfsThreadLeakTest, SharedFSAutoReplicaFailoverTest, DeleteInactiveReplicaTest, TestAuthorizationFramework, HdfsChaosMonkeyNothingIsSafeTest, CloneFieldUpdateProcessorFactoryTest, LeaderFailureAfterFreshStartTest, ScriptEngineTest, CdcrVersionReplicationTest, SuggesterTSTTest, AtomicUpdatesTest, RollingRestartTest, TestFoldingMultitermQuery, TestSuggestSpellingConverter, PreAnalyzedFieldTest, CoreAdminHandlerTest, TestSolrCloudWithSecureImpersonation, SimpleMLTQParserTest, TestDefaultStatsCache, PeerSyncWithIndexFingerprintCachingTest, TestLMDirichletSimilarityFactory, URLClassifyProcessorTest, EchoParamsTest, BigEndianAscendingWordSerializerTest, TestAnalyzeInfixSuggestions, TestRestoreCore, TestRawTransformer, DirectUpdateHandlerOptimizeTest, SignatureUpdateProcessorFactoryTest, ConfigSetsAPITest, FileBasedSpellCheckerTest, TestSolrDeletionPolicy2, OverseerStatusTest, TestQueryUtils, AddBlockUpdateTest, TestHdfsCloudBackupRestore, TestUtils, TestSolr4Spatial, SolrIndexSplitterTest, TestLeaderElectionZkExpiry, RequestHandlersTest, ShowFileRequestHandlerTest, SecurityConfHandlerTest, TestDynamicFieldResource, TestFieldSortValues, RAMDirectoryFactoryTest, TestGraphTermsQParserPlugin, TestConfigSetsAPI, DistributedQueryComponentOptimizationTest, HdfsCollectionsAPIDistributedZkTest, UpdateParamsTest, TestUseDocValuesAsStored2, SuggesterTest, JsonLoaderTest, TestFieldCacheReopen, CoreSorterTest, UpdateRequestProcessorFactoryTest, TestRandomFaceting, NotRequiredUniqueKeyTest, SimpleCollectionCreateDeleteTest, TestFuzzyAnalyzedSuggestions, TestRequestForwarding, BlockJoinFacetDistribTest, TestUniqueKeyFieldResource, DistributedFacetPivotLargeTest, TestClusterProperties, DateRangeFieldTest, SpellingQueryConverterTest, TestQueryWrapperFilter, TestManagedSchemaAPI, PreAnalyzedFieldManagedSchemaCloudTest, SuggesterWFSTTest, EnumFieldTest, FieldAnalysisRequestHandlerTest, TestCodecSupport, TestReversedWildcardFilterFactory, TestStressRecovery, TestWriterPerf, TestSolrDynamicMBean, TestRecovery, TestSolrCLIRunExample, DeleteNodeTest, TestHighFrequencyDictionaryFactory, TestSortByMinMaxFunction, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, ShardRoutingTest, RecoveryZkTest, ClusterStateUpdateTest, LeaderElectionTest, AutoCommitTest]
   [junit4] Completed [354/673 (1!)] on J1 in 7.08s, 3 tests, 1 error <<< FAILURES!

[...truncated 555 lines...]
   [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsRecoveryZkTest
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_E3B52153CC2CEEE2-001/init-core-data-001
   [junit4]   2> 2267704 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-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> 2267706 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-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.hdfs.HdfsRecoveryZkTest_E3B52153CC2CEEE2-001/tempDir-001
   [junit4]   2> 2267707 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2267707 INFO  (Thread-7638) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2267707 INFO  (Thread-7638) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2267807 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:55838
   [junit4]   2> 2267819 INFO  (jetty-launcher-4437-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2267819 INFO  (jetty-launcher-4437-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2267822 INFO  (jetty-launcher-4437-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b6e910c{/solr,null,AVAILABLE}
   [junit4]   2> 2267822 INFO  (jetty-launcher-4437-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@288a3a63{/solr,null,AVAILABLE}
   [junit4]   2> 2267823 INFO  (jetty-launcher-4437-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@24c3795b{SSL,[ssl, http/1.1]}{127.0.0.1:43737}
   [junit4]   2> 2267823 INFO  (jetty-launcher-4437-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@656eb2ad{SSL,[ssl, http/1.1]}{127.0.0.1:56506}
   [junit4]   2> 2267823 INFO  (jetty-launcher-4437-thread-1) [    ] o.e.j.s.Server Started @2272182ms
   [junit4]   2> 2267823 INFO  (jetty-launcher-4437-thread-2) [    ] o.e.j.s.Server Started @2272182ms
   [junit4]   2> 2267823 INFO  (jetty-launcher-4437-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43737}
   [junit4]   2> 2267823 INFO  (jetty-launcher-4437-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56506}
   [junit4]   2> 2267823 ERROR (jetty-launcher-4437-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2267823 ERROR (jetty-launcher-4437-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-01T02:56:13.470Z
   [junit4]   2> 2267824 INFO  (jetty-launcher-4437-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-01T02:56:13.470Z
   [junit4]   2> 2267829 INFO  (jetty-launcher-4437-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2267829 INFO  (jetty-launcher-4437-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2267838 INFO  (jetty-launcher-4437-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55838/solr
   [junit4]   2> 2267838 INFO  (jetty-launcher-4437-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55838/solr
   [junit4]   2> 2267887 INFO  (jetty-launcher-4437-thread-2) [n:127.0.0.1:56506_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56506_solr
   [junit4]   2> 2267888 INFO  (jetty-launcher-4437-thread-1) [n:127.0.0.1:43737_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43737_solr
   [junit4]   2> 2267890 INFO  (jetty-launcher-4437-thread-2) [n:127.0.0.1:56506_solr    ] o.a.s.c.Overseer Overseer (id=97205575572193285-127.0.0.1:56506_solr-n_0000000000) starting
   [junit4]   2> 2267891 INFO  (zkCallback-4448-thread-1-processing-n:127.0.0.1:43737_solr) [n:127.0.0.1:43737_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2267892 INFO  (zkCallback-4447-thread-1-processing-n:127.0.0.1:56506_solr) [n:127.0.0.1:56506_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2267910 INFO  (jetty-launcher-4437-thread-2) [n:127.0.0.1:56506_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56506_solr
   [junit4]   2> 2267913 INFO  (zkCallback-4448-thread-1-processing-n:127.0.0.1:43737_solr) [n:127.0.0.1:43737_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2267913 INFO  (zkCallback-4447-thread-1-processing-n:127.0.0.1:56506_solr) [n:127.0.0.1:56506_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2267948 INFO  (jetty-launcher-4437-thread-1) [n:127.0.0.1:43737_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.hdfs.HdfsRecoveryZkTest_E3B52153CC2CEEE2-001/tempDir-001/node1/.
   [junit4]   2> 2268186 INFO  (jetty-launcher-4437-thread-2) [n:127.0.0.1:56506_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.hdfs.HdfsRecoveryZkTest_E3B52153CC2CEEE2-001/tempDir-001/node2/.
   [junit4]   2> 2268940 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2268942 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55838/solr ready
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 2269032 WARN  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 2269042 WARN  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 2269044 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 2269059 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs to ./temp/Jetty_solaris.vm_45738_hdfs____ly3xal/webapp
   [junit4]   2> 2269608 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@solaris-vm:45738
   [junit4]   2> 2269744 WARN  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 2269747 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 2269763 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_34145_datanode____.4r9ulk/webapp
   [junit4]   2> 2270332 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:34145
   [junit4]   2> 2270421 WARN  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 2270422 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 2270440 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_59695_datanode____9m2pat/webapp
   [junit4]   2> 2270512 INFO  (IPC Server handler 3 on 44615) [    ] BlockStateChange BLOCK* processReport: from storage DS-9d65aee8-7d9b-4834-a7f0-5359a07910bc node DatanodeRegistration(127.0.0.1:41658, datanodeUuid=e1d088f4-f222-4006-b0c5-f0103171c9f0, infoPort=52271, infoSecurePort=0, ipcPort=65067, storageInfo=lv=-56;cid=testClusterID;nsid=1275677366;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
   [junit4]   2> 2270512 INFO  (IPC Server handler 3 on 44615) [    ] BlockStateChange BLOCK* processReport: from storage DS-835fed7e-ba36-45d0-a883-63dc960c19f9 node DatanodeRegistration(127.0.0.1:41658, datanodeUuid=e1d088f4-f222-4006-b0c5-f0103171c9f0, infoPort=52271, infoSecurePort=0, ipcPort=65067, storageInfo=lv=-56;cid=testClusterID;nsid=1275677366;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
   [junit4]   2> 2270996 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:59695
   [junit4]   2> 2271143 INFO  (IPC Server handler 5 on 44615) [    ] BlockStateChange BLOCK* processReport: from storage DS-b6c14c5f-7e80-4690-8dec-24cebe84e213 node DatanodeRegistration(127.0.0.1:42772, datanodeUuid=da62bbb2-edb6-41fd-b56f-3b359ec4d185, infoPort=39317, infoSecurePort=0, ipcPort=45943, storageInfo=lv=-56;cid=testClusterID;nsid=1275677366;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
   [junit4]   2> 2271143 INFO  (IPC Server handler 5 on 44615) [    ] BlockStateChange BLOCK* processReport: from storage DS-edcfa73e-6e0c-446a-9149-4769cc537f9a node DatanodeRegistration(127.0.0.1:42772, datanodeUuid=da62bbb2-edb6-41fd-b56f-3b359ec4d185, infoPort=39317, infoSecurePort=0, ipcPort=45943, storageInfo=lv=-56;cid=testClusterID;nsid=1275677366;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
   [junit4]   2> 2271236 INFO  (TEST-HdfsRecoveryZkTest.test-seed#[E3B52153CC2CEEE2]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2271259 INFO  (qtp2058383248-27080) [n:127.0.0.1:43737_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&maxShardsPerNode=1&name=recoverytest&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2271262 INFO  (OverseerThreadFactory-10097-thread-1-processing-n:127.0.0.1:56506_solr) [n:127.0.0.1:56506_solr    ] o.a.s.c.CreateCollectionCmd Create collection recoverytest
   [junit4]   2> 2271385 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica1&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2
   [junit4]   2> 2271399 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica2&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2
   [junit4]   2> 2271506 INFO  (zkCallback-4448-thread-1-processing-n:127.0.0.1:43737_solr) [n:127.0.0.1:43737_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2271506 INFO  (zkCallback-4447-thread-3-processing-n:127.0.0.1:56506_solr) [n:127.0.0.1:56506_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2272404 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2272413 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.s.IndexSchema [recoverytest_shard1_replica1] Schema name=minimal
   [junit4]   2> 2272413 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2272416 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2272418 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'recoverytest_shard1_replica1' using configuration from collection recoverytest
   [junit4]   2> 2272420 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.s.IndexSchema [recoverytest_shard1_replica2] Schema name=minimal
   [junit4]   2> 2272422 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2272423 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'recoverytest_shard1_replica2' using configuration from collection recoverytest
   [junit4]   2> 2272426 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://solaris-vm:44615/data
   [junit4]   2> 2272426 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
   [junit4]   2> 2272426 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.SolrCore [[recoverytest_shard1_replica1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_E3B52153CC2CEEE2-001/tempDir-001/node1/recoverytest_shard1_replica1], dataDir=[hdfs://solaris-vm:44615/data/recoverytest/core_node1/data/]
   [junit4]   2> 2272426 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://solaris-vm:44615/data/recoverytest/core_node1/data/snapshot_metadata
   [junit4]   2> 2272427 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://solaris-vm:44615/data
   [junit4]   2> 2272427 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
   [junit4]   2> 2272427 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.SolrCore [[recoverytest_shard1_replica2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_E3B52153CC2CEEE2-001/tempDir-001/node2/recoverytest_shard1_replica2], dataDir=[hdfs://solaris-vm:44615/data/recoverytest/core_node2/data/]
   [junit4]   2> 2272428 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://solaris-vm:44615/data/recoverytest/core_node2/data/snapshot_metadata
   [junit4]   2> 2272464 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true]
   [junit4]   2> 2272464 INFO  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [16777216] will allocate [1] slabs and use ~[16777216] bytes
   [junit4]   2> 2272464 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true]
   [junit4]   2> 2272465 INFO  (qtp2038592928-27091) [n:127.0.0.1:56506_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [16777216] will allocate [1] slabs and use ~[16777216] bytes
   [junit4]   2> 2272467 WARN  (qtp2058383248-27081) [n:127.0.0.1:43737_solr c:recoverytest s:shard1  x:recoverytest_shard1_replica1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again.
   [junit4]   2> 2272467 WARN  (qtp2038592928

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

otsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:203)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3370)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:5729)
   [junit4]   2> 	... 54 more
   [junit4]   2> 2293572 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55838 55838
   [junit4]   2> 2293572 INFO  (SUITE-HdfsRecoveryZkTest-seed#[E3B52153CC2CEEE2]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {}, locale=lv, timezone=Europe/Moscow
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112 (64-bit)/cpus=3,threads=2,free=132775648,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [NumericFieldsTest, TestBlobHandler, TestLegacyNumericRangeQueryBuilder, TestSQLHandler, DistributedFacetPivotSmallTest, HdfsWriteToMultipleCollectionsTest, BasicDistributedZkTest, TestReplicationHandler, TestExactStatsCache, ReplaceNodeTest, HdfsNNFailoverTest, MigrateRouteKeyTest, TestBinaryField, TestFieldCache, TestFieldTypeCollectionResource, DistanceUnitsTest, TestCSVLoader, TestReloadDeadlock, TestOrdValues, SystemInfoHandlerTest, TestFunctionQuery, RuleEngineTest, AnalyticsMergeStrategyTest, TestBlendedInfixSuggestions, TestBulkSchemaAPI, SolrMetricManagerTest, PathHierarchyTokenizerFactoryTest, DOMUtilTest, TermsComponentTest, AnalysisErrorHandlingTest, HdfsSyncSliceTest, AnalysisAfterCoreReloadTest, HLLUtilTest, TestGroupingSearch, TestDocumentBuilder, DistributedQueryComponentCustomSortTest, TestNumericTerms32, SuggesterFSTTest, ParsingFieldUpdateProcessorsTest, TestMissingGroups, BaseCdcrDistributedZkTest, CheckHdfsIndexTest, ConcurrentDeleteAndCreateCollectionTest, DirectUpdateHandlerTest, TestCopyFieldCollectionResource, TestUseDocValuesAsStored, ReplicaListTransformerTest, CloudMLTQParserTest, TestExtendedDismaxParser, TestTolerantUpdateProcessorCloud, QueryElevationComponentTest, TestSolrDeletionPolicy1, CoreMergeIndexesAdminHandlerTest, CollectionStateFormat2Test, TestRTGBase, TestManagedSchemaThreadSafety, TestFieldCacheSanityChecker, MoreLikeThisHandlerTest, TestHdfsUpdateLog, TestSlowCompositeReaderWrapper, TestLeaderElectionWithEmptyReplica, SolrMetricsIntegrationTest, TestFastOutputStream, TestSolrCoreSnapshots, DistributedSuggestComponentTest, TestPseudoReturnFields, DistributedQueueTest, TestConfigSets, SolrCoreTest, DeleteLastCustomShardedReplicaTest, TestLazyCores, TestExclusionRuleCollectionAccess, OutOfBoxZkACLAndCredentialsProvidersTest, TestSimpleQParserPlugin, TestJsonFacets, TestHighlightDedupGrouping, SegmentsInfoRequestHandlerTest, BadCopyFieldTest, TestInitParams, TestLRUCache, UniqFieldsUpdateProcessorFactoryTest, TestCollectionAPI, BufferStoreTest, ZkCLITest, ZkControllerTest, TestClusterStateMutator, TestSchemaNameResource, DirectoryFactoryTest, HardAutoCommitTest, TestCharFilters, ShardSplitTest, TestJettySolrRunner, TestSolrJ, TestXmlQParserPlugin, ConjunctionSolrSpellCheckerTest, SearchHandlerTest, TestFieldCollectionResource, TestManagedResourceStorage, PluginInfoTest, SSLMigrationTest, XmlUpdateRequestHandlerTest, TestDynamicFieldCollectionResource, QueryEqualityTest, TestWordDelimiterFilterFactory, TestOnReconnectListenerSupport, TestSweetSpotSimilarityFactory, TestReRankQParserPlugin, TestCollationField, TestConfigSetImmutable, TestFreeTextSuggestions, TestTrackingShardHandlerFactory, ZkNodePropsTest, TestElisionMultitermQuery, CdcrRequestHandlerTest, TestIBSimilarityFactory, DistributedQueryElevationComponentTest, PeerSyncTest, TestDocBasedVersionConstraints, TestSolrQueryResponse, TestHashQParserPlugin, TestExpandComponent, DistributedDebugComponentTest, TestCollapseQParserPlugin, TestStressReorder, SimpleFacetsTest, TestJmxIntegration, RestartWhileUpdatingTest, TestTrieFacet, SpatialFilterTest, OpenExchangeRatesOrgProviderTest, TestJavabinTupleStreamParser, TestDistribDocBasedVersion, TestSchemaSimilarityResource, TestSolr4Spatial2, CachingDirectoryFactoryTest, TestManagedStopFilterFactory, BasicAuthIntegrationTest, SharedFSAutoReplicaFailoverUtilsTest, TestRequestStatusCollectionAPI, TestCustomStream, IndexSchemaRuntimeFieldTest, GraphQueryTest, TestLeaderInitiatedRecoveryThread, PrimitiveFieldTypeTest, SampleTest, TestCursorMarkWithoutUniqueKey, TestSolrQueryParserResource, TestStressVersions, TestIntervalFaceting, SolrXmlInZkTest, TlogReplayBufferedWhileIndexingTest, TestPerFieldSimilarity, LeaderInitiatedRecoveryOnCommitTest, DirectSolrSpellCheckerTest, TestFastLRUCache, CdcrReplicationDistributedZkTest, OverseerTaskQueueTest, BitVectorTest, CursorMarkTest, DistribDocExpirationUpdateProcessorTest, TestSolrConfigHandlerCloud, TestLFUCache, DocValuesTest, TestRawResponseWriter, OpenCloseCoreStressTest, OverseerTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestRandomDVFaceting, ShardRoutingCustomTest, TestDistributedSearch, TestRealTimeGet, TestReload, TestCoreContainer, StatsComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, TestSort, HighlighterTest, CurrencyFieldOpenExchangeTest, CurrencyFieldXmlFileTest, SimplePostToolTest, TestCoreDiscovery, SolrRequestParserTest, TestStressLucene, TestTrie, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, XsltUpdateRequestHandlerTest, DebugComponentTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, TestQueryTypes, TestValueSourceCache, RequiredFieldsTest, LoggingHandlerTest, SolrPluginUtilsTest, ReturnFieldsTest, MBeansHandlerTest, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, PingRequestHandlerTest, SpellPossibilityIteratorTest, TestXIncludeConfig, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, TimeZoneUtilsTest, DateMathParserTest, TestSystemIdResolver, PrimUtilsTest, DateFieldTest, ClusterStateTest, SliceStateTest, FileUtilsTest, DistributedMLTComponentTest, CursorPagingTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestDistributedMissingSort, TestSimpleTrackingShardHandler, ActionThrottleTest, AssignTest, AsyncCallRequestStatusResponseTest, CdcrReplicationHandlerTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, CreateCollectionCleanupTest, CustomCollectionTest, DeleteShardTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, HttpPartitionTest, LeaderInitiatedRecoveryOnShardRestartTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, PeerSyncReplicationTest, TestCloudRecovery, TestDownShardTolerantSearch, TestLocalFSCloudBackupRestore, TestMiniSolrCloudClusterSSL, TestReplicaProperties, TestShortCircuitedRequests, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestStressCloudBlindAtomicUpdates, TestStressLiveNodes, TestTolerantUpdateProcessorRandomCloud, HdfsRecoveryZkTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=HdfsRecoveryZkTest -Dtests.seed=E3B52153CC2CEEE2 -Dtests.slow=true -Dtests.locale=lv -Dtests.timezone=Europe/Moscow -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J0 | HdfsRecoveryZkTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
   [junit4]    > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]    > 	at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
   [junit4]    > 	at org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130)
   [junit4]    > 	at org.apache.solr.update.HdfsUpdateLog.init(HdfsUpdateLog.java:202)
   [junit4]    > 	at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:137)
   [junit4]    > 	at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:94)
   [junit4]    > 	at org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:102)
   [junit4]    > 	at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
   [junit4]    > 	at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:747)
   [junit4]    > 	at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:809)
   [junit4]    > 	at org.apache.solr.core.SolrCore.initUpdateHandler(SolrCore.java:1060)
   [junit4]    > 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:925)
   [junit4]    > 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:817)
   [junit4]    > 	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:906)
   [junit4]    > 	at org.apache.solr.core.CoreContainer.lambda$load$3(CoreContainer.java:551)
   [junit4]    > 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    > 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]    > 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]    > 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([E3B52153CC2CEEE2]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:266)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [532/673 (2!)] on J0 in 56.99s, 1 test, 1 failure <<< FAILURES!

[...truncated 62894 lines...]


Mime
View raw message