You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/11/08 05:08:44 UTC

[JENKINS] Lucene-Solr-Tests-6.3 - Build # 5 - Unstable

Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.3/5/

1 tests failed.
FAILED:  org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test

Error Message:
int_i: goodEst=13946, poorEst=13970, real=13975, p=q=id:[284+TO+14258]&rows=0&stats=true&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_int_i}int_i&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_int_i}int_i&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_long_l}long_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_long_l}long_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_string_s}string_s&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_string_s}string_s&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l

Stack Trace:
java.lang.AssertionError: int_i: goodEst=13946, poorEst=13970, real=13975, p=q=id:[284+TO+14258]&rows=0&stats=true&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_int_i}int_i&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_int_i}int_i&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_long_l}long_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_long_l}long_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_string_s}string_s&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_string_s}string_s&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l
	at __randomizedtesting.SeedInfo.seed([D0DA16F367EE3305:588E2929C9125EFD]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:217)
	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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
	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)




Build Log:
[...truncated 12520 lines...]
   [junit4] Suite: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/init-core-data-001
   [junit4]   2> 1921134 INFO  (SUITE-TestDistributedStatsComponentCardinality-seed#[D0DA16F367EE3305]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9062)
   [junit4]   2> 1921135 INFO  (SUITE-TestDistributedStatsComponentCardinality-seed#[D0DA16F367EE3305]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /fniom/rw
   [junit4]   2> 1922568 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1922570 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1922572 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@171d5cd8{/fniom/rw,null,AVAILABLE}
   [junit4]   2> 1922575 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.ServerConnector Started ServerConnector@2889a573{HTTP/1.1,[http/1.1]}{127.0.0.1:53079}
   [junit4]   2> 1922575 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server Started @1926335ms
   [junit4]   2> 1922575 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fniom/rw, hostPort=53079, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/control/cores}
   [junit4]   2> 1922575 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1922583 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 1922583 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1922583 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1922583 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-08T04:35:41.510Z
   [junit4]   2> 1922583 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/control/solr.xml
   [junit4]   2> 1922590 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1922592 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/control/cores
   [junit4]   2> 1922593 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1922594 INFO  (coreLoadExecutor-8438-thread-1) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1922625 INFO  (coreLoadExecutor-8438-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 1922648 INFO  (coreLoadExecutor-8438-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1922758 WARN  (coreLoadExecutor-8438-thread-1) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1922760 INFO  (coreLoadExecutor-8438-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1922768 INFO  (coreLoadExecutor-8438-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1922768 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1922768 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a5a7476
   [junit4]   2> 1922769 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8604531500504574]
   [junit4]   2> 1922787 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1922787 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1922787 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1922787 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1922788 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=94.61328125, floorSegmentMB=1.484375, forceMergeDeletesPctAllowed=29.137137197875923, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1922788 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a51f4b0[collection1] main]
   [junit4]   2> 1922789 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1922798 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1922826 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1922827 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1922827 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1922827 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1922827 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1922827 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1922828 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1922828 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1922828 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1922828 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1922829 INFO  (searcherExecutor-8439-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a51f4b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1922829 INFO  (coreLoadExecutor-8438-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1550403231291539456
   [junit4]   2> 1923274 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1923275 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1923355 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@260b08b4{/fniom/rw,null,AVAILABLE}
   [junit4]   2> 1923355 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.ServerConnector Started ServerConnector@451ca477{HTTP/1.1,[http/1.1]}{127.0.0.1:44370}
   [junit4]   2> 1923355 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server Started @1927115ms
   [junit4]   2> 1923356 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fniom/rw, hostPort=44370, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard0/cores}
   [junit4]   2> 1923356 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1923364 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 1923364 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1923364 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1923364 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-08T04:35:42.291Z
   [junit4]   2> 1923365 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1923371 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1923373 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard0/cores
   [junit4]   2> 1923373 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1923374 INFO  (coreLoadExecutor-8449-thread-1) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1923436 INFO  (coreLoadExecutor-8449-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 1923468 INFO  (coreLoadExecutor-8449-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1923614 WARN  (coreLoadExecutor-8449-thread-1) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1923616 INFO  (coreLoadExecutor-8449-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1923623 INFO  (coreLoadExecutor-8449-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1923624 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1923624 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a5a7476
   [junit4]   2> 1923625 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8604531500504574]
   [junit4]   2> 1923642 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1923642 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1923643 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1923643 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1923650 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=94.61328125, floorSegmentMB=1.484375, forceMergeDeletesPctAllowed=29.137137197875923, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1923650 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@35259660[collection1] main]
   [junit4]   2> 1923651 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1923658 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1923689 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1923690 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1923690 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1923690 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1923690 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1923690 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1923691 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1923691 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1923691 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1923691 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1923692 INFO  (searcherExecutor-8450-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@35259660[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1923693 INFO  (coreLoadExecutor-8449-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1550403232197509120
   [junit4]   2> 1924122 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard1/cores/collection1
   [junit4]   2> 1924124 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1924126 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@61a02534{/fniom/rw,null,AVAILABLE}
   [junit4]   2> 1924126 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.ServerConnector Started ServerConnector@4bc360ed{HTTP/1.1,[http/1.1]}{127.0.0.1:49884}
   [junit4]   2> 1924126 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server Started @1927886ms
   [junit4]   2> 1924126 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fniom/rw, hostPort=49884, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard1/cores}
   [junit4]   2> 1924127 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1924131 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 1924131 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1924131 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1924131 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-08T04:35:43.058Z
   [junit4]   2> 1924131 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard1/solr.xml
   [junit4]   2> 1924139 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1924140 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard1/cores
   [junit4]   2> 1924140 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1924141 INFO  (coreLoadExecutor-8460-thread-1) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1924177 INFO  (coreLoadExecutor-8460-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 1924204 INFO  (coreLoadExecutor-8460-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1924304 WARN  (coreLoadExecutor-8460-thread-1) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1924306 INFO  (coreLoadExecutor-8460-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1924312 INFO  (coreLoadExecutor-8460-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1924313 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard1/cores/collection1/data/]
   [junit4]   2> 1924313 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a5a7476
   [junit4]   2> 1924314 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8604531500504574]
   [junit4]   2> 1924331 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1924331 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1924332 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1924332 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1924339 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=94.61328125, floorSegmentMB=1.484375, forceMergeDeletesPctAllowed=29.137137197875923, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1924339 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@74570c7b[collection1] main]
   [junit4]   2> 1924339 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1924348 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1924376 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1924377 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1924377 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1924377 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1924377 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1924377 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1924377 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1924378 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1924378 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1924378 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1924379 INFO  (searcherExecutor-8461-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74570c7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1924380 INFO  (coreLoadExecutor-8460-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1550403232917880832
   [junit4]   2> 1924816 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard2/cores/collection1
   [junit4]   2> 1924817 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1924944 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@29487c04{/fniom/rw,null,AVAILABLE}
   [junit4]   2> 1924944 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.ServerConnector Started ServerConnector@8030ba0{HTTP/1.1,[http/1.1]}{127.0.0.1:47181}
   [junit4]   2> 1924944 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.Server Started @1928704ms
   [junit4]   2> 1924945 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fniom/rw, hostPort=47181, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard2/cores}
   [junit4]   2> 1924945 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1924970 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.3.0
   [junit4]   2> 1924971 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1924971 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1924971 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-08T04:35:43.898Z
   [junit4]   2> 1924971 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard2/solr.xml
   [junit4]   2> 1924979 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1924983 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard2/cores
   [junit4]   2> 1924983 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1924990 INFO  (coreLoadExecutor-8471-thread-1) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1925093 INFO  (coreLoadExecutor-8471-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
   [junit4]   2> 1925137 INFO  (coreLoadExecutor-8471-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1925303 WARN  (coreLoadExecutor-8471-thread-1) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1925305 INFO  (coreLoadExecutor-8471-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1925311 INFO  (coreLoadExecutor-8471-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1925312 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001/tempDir-001/shard2/cores/collection1/data/]
   [junit4]   2> 1925312 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a5a7476
   [junit4]   2> 1925313 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8604531500504574]
   [junit4]   2> 1925359 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1925359 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1925359 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1925360 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1925367 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=94.61328125, floorSegmentMB=1.484375, forceMergeDeletesPctAllowed=29.137137197875923, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1925367 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@330da33f[collection1] main]
   [junit4]   2> 1925367 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1925376 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1925405 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1925405 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1925406 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1925406 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1925406 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1925406 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1925407 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1925407 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1925407 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1925407 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1925408 INFO  (searcherExecutor-8472-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@330da33f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1925409 INFO  (coreLoadExecutor-8471-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1550403233996865536
   [junit4]   2> 1925602 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1925602 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.h.c.TestDistributedStatsComponentCardinality Building an index of 14579 docs
   [junit4]   2> 1925618 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[1 (1550403234204483584)]} 0 11
   [junit4]   2> 1925625 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[1 (1550403234218115072)]} 0 5
   [junit4]   2> 1925628 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[2 (1550403234226503680)]} 0 0
   [junit4]   2> 1925636 INFO  (qtp2019965900-59988) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[2 (1550403234227552256)]} 0 6
   [junit4]   2> 1925638 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[3 (1550403234236989440)]} 0 0
   [junit4]   2> 1925660 INFO  (qtp567168362-60005) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[3 (1550403234249572352)]} 0 9
   [junit4]   2> 1925662 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[4 (1550403234262155264)]} 0 0
   [junit4]   2> 1925670 INFO  (qtp925506025-59972) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[4 (1550403234270543872)]} 0 0
   [junit4]   2> 1925672 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[5 (1550403234272641024)]} 0 0
   [junit4]   2> 1925674 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[5 (1550403234274738176)]} 0 0
   [junit4]   2> 1925676 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[6 (1550403234276835328)]} 0 0
   [junit4]   2> 1925678 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[6 (1550403234278932480)]} 0 0
   [junit4]   2> 1925680 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[7 (1550403234279981056)]} 0 0
   [junit4]   2> 1925681 INFO  (qtp925506025-59972) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[7 (1550403234282078208)]} 0 0
   [junit4]   2> 1925685 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[8 (1550403234286272512)]} 0 0
   [junit4]   2> 1925687 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[8 (1550403234288369664)]} 0 0
   [junit4]   2> 1925689 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[9 (1550403234290466816)]} 0 0
   [junit4]   2> 1925691 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[9 (1550403234292563968)]} 0 0
   [junit4]   2> 1925698 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[10 (1550403234299904000)]} 0 0
   [junit4]   2> 1925700 INFO  (qtp925506025-59972) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[10 (1550403234302001152)]} 0 0
   [junit4]   2> 1925702 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[11 (1550403234304098304)]} 0 0
   [junit4]   2> 1925704 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[11 (1550403234306195456)]} 0 0
   [junit4]   2> 1925706 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[12 (1550403234307244032)]} 0 0
   [junit4]   2> 1925707 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[12 (1550403234309341184)]} 0 0
   [junit4]   2> 1925709 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[13 (1550403234311438336)]} 0 0
   [junit4]   2> 1925711 INFO  (qtp925506025-59972) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[13 (1550403234313535488)]} 0 0
   [junit4]   2> 1925713 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[14 (1550403234315632640)]} 0 0
   [junit4]   2> 1925714 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[14 (1550403234316681216)]} 0 0
   [junit4]   2> 1925716 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[15 (1550403234318778368)]} 0 0
   [junit4]   2> 1925718 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[15 (1550403234320875520)]} 0 0
   [junit4]   2> 1925722 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[16 (1550403234325069824)]} 0 0
   [junit4]   2> 1925724 INFO  (qtp925506025-59968) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[16 (1550403234327166976)]} 0 0
   [junit4]   2> 1925726 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[17 (1550403234329264128)]} 0 0
   [junit4]   2> 1925728 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[17 (1550403234331361280)]} 0 0
   [junit4]   2> 1925730 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[18 (1550403234333458432)]} 0 0
   [junit4]   2> 1925732 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[18 (1550403234334507008)]} 0 0
   [junit4]   2> 1925733 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[19 (1550403234336604160)]} 0 0
   [junit4]   2> 1925735 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[19 (1550403234338701312)]} 0 0
   [junit4]   2> 1925737 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[20 (1550403234340798464)]} 0 0
   [junit4]   2> 1925739 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[20 (1550403234342895616)]} 0 0
   [junit4]   2> 1925741 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[21 (1550403234343944192)]} 0 0
   [junit4]   2> 1925742 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[21 (1550403234346041344)]} 0 0
   [junit4]   2> 1925744 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[22 (1550403234348138496)]} 0 0
   [junit4]   2> 1925746 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[22 (1550403234350235648)]} 0 0
   [junit4]   2> 1925748 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[23 (1550403234352332800)]} 0 0
   [junit4]   2> 1925750 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[23 (1550403234353381376)]} 0 0
   [junit4]   2> 1925751 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[24 (1550403234355478528)]} 0 0
   [junit4]   2> 1925753 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[24 (1550403234357575680)]} 0 0
   [junit4]   2> 1925755 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[25 (1550403234359672832)]} 0 0
   [junit4]   2> 1925757 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[25 (1550403234360721408)]} 0 0
   [junit4]   2> 1925758 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[26 (1550403234362818560)]} 0 0
   [junit4]   2> 1925760 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[26 (1550403234364915712)]} 0 0
   [junit4]   2> 1925762 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[27 (1550403234367012864)]} 0 0
   [junit4]   2> 1925768 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[27 (1550403234372255744)]} 0 0
   [junit4]   2> 1925769 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[28 (1550403234374352896)]} 0 0
   [junit4]   2> 1925771 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[28 (1550403234376450048)]} 0 0
   [junit4]   2> 1925773 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[29 (1550403234378547200)]} 0 0
   [junit4]   2> 1925775 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[29 (1550403234380644352)]} 0 0
   [junit4]   2> 1925777 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[30 (1550403234382741504)]} 0 0
   [junit4]   2> 1925778 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[30 (1550403234383790080)]} 0 0
   [junit4]   2> 1925780 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[31 (1550403234385887232)]} 0 0
   [junit4]   2> 1925782 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[31 (1550403234387984384)]} 0 0
   [junit4]   2> 1925784 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[32 (1550403234390081536)]} 0 0
   [junit4]   2> 1925785 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[32 (1550403234391130112)]} 0 0
   [junit4]   2> 1925787 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[33 (1550403234393227264)]} 0 0
   [junit4]   2> 1925789 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[33 (1550403234395324416)]} 0 0
   [junit4]   2> 1925791 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[34 (1550403234397421568)]} 0 0
   [junit4]   2> 1925793 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[34 (1550403234398470144)]} 0 0
   [junit4]   2> 1925794 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[35 (1550403234400567296)]} 0 0
   [junit4]   2> 1925796 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[35 (1550403234402664448)]} 0 0
   [junit4]   2> 1925798 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[36 (1550403234404761600)]} 0 0
   [junit4]   2> 1925800 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[36 (1550403234405810176)]} 0 0
   [junit4]   2> 1925801 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[37 (1550403234407907328)]} 0 0
   [junit4]   2> 1925803 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[37 (1550403234410004480)]} 0 0
   [junit4]   2> 1925805 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[38 (1550403234412101632)]} 0 0
   [junit4]   2> 1925807 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[38 (1550403234413150208)]} 0 0
   [junit4]   2> 1925808 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[39 (1550403234415247360)]} 0 0
   [junit4]   2> 1925811 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[39 (1550403234418393088)]} 0 0
   [junit4]   2> 1925813 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[40 (1550403234419441664)]} 0 0
   [junit4]   2> 1925814 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[40 (1550403234421538816)]} 0 0
   [junit4]   2> 1925816 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[41 (1550403234423635968)]} 0 0
   [junit4]   2> 1925818 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[41 (1550403234425733120)]} 0 0
   [junit4]   2> 1925820 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[42 (1550403234427830272)]} 0 0
   [junit4]   2> 1925822 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[42 (1550403234429927424)]} 0 0
   [junit4]   2> 1925824 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[43 (1550403234430976000)]} 0 0
   [junit4]   2> 1925825 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[43 (1550403234433073152)]} 0 0
   [junit4]   2> 1925827 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[44 (1550403234435170304)]} 0 0
   [junit4]   2> 1925829 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[44 (1550403234437267456)]} 0 0
   [junit4]   2> 1925831 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[45 (1550403234439364608)]} 0 0
   [junit4]   2> 1925833 INFO  (qtp567168362-60009) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[45 (1550403234440413184)]} 0 0
   [junit4]   2> 1925835 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[46 (1550403234442510336)]} 0 0
   [junit4]   2> 1925836 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[46 (1550403234444607488)]} 0 0
   [junit4]   2> 1925838 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[47 (1550403234446704640)]} 0 0
   [junit4]   2> 1925840 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[47 (1550403234448801792)]} 0 0
   [junit4]   2> 1925842 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[48 (1550403234449850368)]} 0 0
   [junit4]   2> 1925843 INFO  (qtp567168362-60009) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[48 (1550403234451947520)]} 0 0
   [junit4]   2> 1925845 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[49 (1550403234454044672)]} 0 0
   [junit4]   2> 1925847 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[49 (1550403234456141824)]} 0 0
   [junit4]   2> 1925849 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[50 (1550403234457190400)]} 0 0
   [junit4]   2> 1925850 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[50 (1550403234459287552)]} 0 0
   [junit4]   2> 1925852 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[51 (1550403234461384704)]} 0 0
   [junit4]   2> 1925854 INFO  (qtp567168362-60009) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[51 (1550403234463481856)]} 0 0
   [junit4]   2> 1925856 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[52 (1550403234464530432)]} 0 0
   [junit4]   2> 1925857 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[52 (1550403234466627584)]} 0 0
   [junit4]   2> 1925859 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[53 (1550403234468724736)]} 0 0
   [junit4]   2> 1925861 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[53 (1550403234470821888)]} 0 0
   [junit4]   2> 1925863 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[54 (1550403234471870464)]} 0 0
   [junit4]   2> 1925864 INFO  (qtp567168362-60007) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[54 (1550403234473967616)]} 0 0
   [junit4]   2> 1925866 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[55 (1550403234476064768)]} 0 0
   [junit4]   2> 1925868 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[55 (1550403234478161920)]} 0 0
   [junit4]   2> 1925870 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[56 (1550403234480259072)]} 0 0
   [junit4]   2> 1925871 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[56 (1550403234481307648)]} 0 0
   [junit4]   2> 1925878 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[57 (1550403234488647680)]} 0 0
   [junit4]   2> 1925880 INFO  (qtp567168362-60008) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[57 (1550403234490744832)]} 0 0
   [junit4]   2> 1925882 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[58 (1550403234492841984)]} 0 0
   [junit4]   2> 1925884 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[58 (1550403234494939136)]} 0 0
   [junit4]   2> 1925886 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[59 (1550403234495987712)]} 0 0
   [junit4]   2> 1925887 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[59 (1550403234498084864)]} 0 0
   [junit4]   2> 1925889 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[60 (1550403234500182016)]} 0 0
   [junit4]   2> 1925891 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[60 (1550403234502279168)]} 0 0
   [junit4]   2> 1925893 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[61 (1550403234503327744)]} 0 0
   [junit4]   2> 1925894 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[61 (1550403234505424896)]} 0 0
   [junit4]   2> 1925896 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[62 (1550403234507522048)]} 0 0
   [junit4]   2> 1925898 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[62 (1550403234509619200)]} 0 0
   [junit4]   2> 1925900 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[63 (1550403234511716352)]} 0 0
   [junit4]   2> 1925902 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[63 (1550403234513813504)]} 0 0
   [junit4]   2> 1925904 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[64 (1550403234514862080)]} 0 0
   [junit4]   2> 1925906 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[64 (1550403234518007808)]} 0 0
   [junit4]   2> 1925910 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[65 (1550403234522202112)]} 0 0
   [junit4]   2> 1925912 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[65 (1550403234524299264)]} 0 0
   [junit4]   2> 1925913 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[66 (1550403234525347840)]} 0 0
   [junit4]   2> 1925915 INFO  (qtp567168362-60006) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[66 (1550403234527444992)]} 0 0
   [junit4]   2> 1925917 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[67 (1550403234529542144)]} 0 0
   [junit4]   2> 1925919 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[67 (1550403234530590720)]} 0 0
   [junit4]   2> 1925920 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[68 (1550403234532687872)]} 0 0
   [junit4]   2> 1925922 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[68 (1550403234534785024)]} 0 0
   [junit4]   2> 1925924 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[69 (1550403234536882176)]} 0 0
   [junit4]   2> 1925927 INFO  (qtp567168362-60009) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[69 (1550403234540027904)]} 0 0
   [junit4]   2> 1925929 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[70 (1550403234542125056)]} 0 0
   [junit4]   2> 1925932 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[70 (1550403234544222208)]} 0 0
   [junit4]   2> 1925933 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[71 (1550403234546319360)]} 0 0
   [junit4]   2> 1925935 INFO  (qtp2019965900-59992) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[71 (1550403234548416512)]} 0 0
   [junit4]   2> 1925937 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[72 (1550403234550513664)]} 0 0
   [junit4]   2> 1925939 INFO  (qtp567168362-60009) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[72 (1550403234552610816)]} 0 0
   [junit4]   2> 1925941 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[73 (1550403234553659392)]} 0 0
   [junit4]   2> 1925942 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[73 (1550403234555756544)]} 0 0
   [junit4]   2> 1925944 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[74 (1550403234557853696)]} 0 0
   [junit4]   2> 1925946 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[74 (1550403234559950848)]} 0 0
   [junit4]   2> 1925948 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[75 (1550403234562048000)]} 0 0
   [junit4]   2> 1925949 INFO  (qtp567168362-60008) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[75 (1550403234563096576)]} 0 0
   [junit4]   2> 1925951 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[76 (1550403234565193728)]} 0 0
   [junit4]   2> 1925953 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[76 (1550403234567290880)]} 0 0
   [junit4]   2> 1925955 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[77 (1550403234569388032)]} 0 0
   [junit4]   2> 1925956 INFO  (qtp2019965900-59985) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[77 (1550403234570436608)]} 0 0
   [junit4]   2> 1925958 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[78 (1550403234572533760)]} 0 0
   [junit4]   2> 1925960 INFO  (qtp567168362-60002) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[78 (1550403234574630912)]} 0 0
   [junit4]   2> 1925962 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[79 (1550403234575679488)]} 0 0
   [junit4]   2> 1925963 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[79 (1550403234577776640)]} 0 0
   [junit4]   2> 1925978 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[80 (1550403234593505280)]} 0 0
   [junit4]   2> 1925980 INFO  (qtp2019965900-59988) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[80 (1550403234595602432)]} 0 0
   [junit4]   2> 1925982 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[81 (1550403234597699584)]} 0 0
   [junit4]   2> 1925984 INFO  (qtp567168362-60002) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[81 (1550403234599796736)]} 0 0
   [junit4]   2> 1925986 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[82 (1550403234601893888)]} 0 0
   [junit4]   2> 1925988 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[82 (1550403234603991040)]} 0 0
   [junit4]   2> 1925990 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[83 (1550403234606088192)]} 0 0
   [junit4]   2> 1925992 INFO  (qtp2019965900-59988) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[83 (1550403234607136768)]} 0 0
   [junit4]   2> 1925993 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[84 (1550403234609233920)]} 0 0
   [junit4]   2> 1925995 INFO  (qtp567168362-60002) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[84 (1550403234611331072)]} 0 0
   [junit4]   2> 1925997 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[85 (1550403234613428224)]} 0 0
   [junit4]   2> 1925999 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[85 (1550403234615525376)]} 0 0
   [junit4]   2> 1926001 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[86 (1550403234616573952)]} 0 0
   [junit4]   2> 1926002 INFO  (qtp2019965900-59988) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[86 (1550403234618671104)]} 0 0
   [junit4]   2> 1926006 INFO  (qtp1352244256-59956) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[87 (1550403234622865408)]} 0 0
   [junit4]   2> 1926008 INFO  (qtp567168362-60002) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[87 (1550403234624962560)]} 0 0
   [junit4]   2> 1926010 INFO  (qtp1352244256-59955) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[88 (1550403234626011136)]} 0 0
   [junit4]   2> 1926011 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[88 (1550403234628108288)]} 0 0
   [junit4]   2> 1926013 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[89 (1550403234630205440)]} 0 0
   [junit4]   2> 1926015 INFO  (qtp2019965900-59988) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[89 (1550403234632302592)]} 0 0
   [junit4]   2> 1926017 INFO  (qtp1352244256-59958) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[90 (1550403234633351168)]} 0 0
   [junit4]   2> 1926018 INFO  (qtp567168362-60002) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[90 (1550403234635448320)]} 0 0
   [junit4]   2> 1926020 INFO  (qtp1352244256-59957) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[91 (1550403234637545472)]} 0 0
   [junit4]   2> 1926022 INFO  (qtp925506025-59971) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[91 (1550403234639642624)]} 0 0
   [junit4]   2> 1926024 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[92 (1550403234641739776)]} 0 0
   [junit4]   2> 1926026 INFO  (qtp2019965900-59989) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[92 (1550403234643836928)]} 0 0
   [junit4]   2> 1926028 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[93 (1550403234645934080)]} 0 0
   [junit4]   2> 1926029 INFO  (qtp567168362-60008) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[93 (1550403234646982656)]} 0 0
   [junit4]   2> 1926031 INFO  (qtp1352244256-59951) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[94 (1550403234649079808)]} 0 0
   [junit4]   2> 1926033 INFO  (qtp925506025-59968) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[94 (1550403234651176960)]} 0 0
   [junit4]   2> 1926035 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[95 (1550403234653274112)]} 0 0
   [junit4]   2> 1926037 INFO  (qtp2019965900-59985) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[95 (1550403234654322688)]} 0 0
   [junit4]   2> 1926038 INFO  (qtp1352244256-59954) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[96 (1550403234656419840)]} 0 0
   [junit4]   2> 1926040 INFO  (qtp567168362-60005) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fniom/rw path=/update params={wt=javabin&version=2}{add=[

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

letContextHandler@61a02534{/fniom/rw,null,UNAVAILABLE}
   [junit4]   2> 2005676 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@8030ba0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2005677 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1394223081
   [junit4]   2> 2005677 INFO  (coreCloseExecutor-8483-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4b49103
   [junit4]   2> 2005688 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[D0DA16F367EE3305]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@29487c04{/fniom/rw,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDistributedStatsComponentCardinality -Dtests.method=test -Dtests.seed=D0DA16F367EE3305 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ru-RU -Dtests.timezone=America/Sao_Paulo -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 84.6s J2 | TestDistributedStatsComponentCardinality.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: int_i: goodEst=13946, poorEst=13970, real=13975, p=q=id:[284+TO+14258]&rows=0&stats=true&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_int_i}int_i&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_int_i}int_i&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_long_l}long_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_long_l}long_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_string_s}string_s&stats.field={!cardinality%3D0.01300996095014778+key%3Dlow_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_string_s}string_s&stats.field={!cardinality%3D0.5130099609501477+key%3Dhigh_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([D0DA16F367EE3305:588E2929C9125EFD]:0)
   [junit4]    > 	at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:217)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2005697 INFO  (SUITE-TestDistributedStatsComponentCardinality-seed#[D0DA16F367EE3305]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.3/solr/build/solr-core/test/J2/temp/solr.handler.component.TestDistributedStatsComponentCardinality_D0DA16F367EE3305-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {int_i=BlockTreeOrds(blocksize=128), range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=Direct), int_i_prehashed_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), long_l_prehashed_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=PostingsFormat(name=MockRandom), long_l=BlockTreeOrds(blocksize=128), _version_=PostingsFormat(name=Direct), string_s_prehashed_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), string_s=BlockTreeOrds(blocksize=128), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=MockRandom)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1579, maxMBSortInHeap=6.735433115280038, sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=ru-RU, timezone=America/Sao_Paulo
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=4,threads=1,free=137924152,total=534249472
   [junit4]   2> NOTE: All tests run in this JVM: [TestUtils, TestZkChroot, DirectUpdateHandlerTest, TestXmlQParser, HdfsChaosMonkeyNothingIsSafeTest, TestCustomSort, UnloadDistributedZkTest, LeaderElectionTest, TestXIncludeConfig, TestCollationField, TestStressCloudBlindAtomicUpdates, SolrXmlInZkTest, TestInfoStreamLogging, TestSolrDynamicMBean, TestRebalanceLeaders, TestSolrDeletionPolicy1, XmlUpdateRequestHandlerTest, VersionInfoTest, TestManagedStopFilterFactory, TestUseDocValuesAsStored, TestDFRSimilarityFactory, CurrencyFieldXmlFileTest, TestSolrQueryParserDefaultOperatorResource, ResponseLogComponentTest, TestReversedWildcardFilterFactory, SuggestComponentTest, TestMacros, TestSubQueryTransformer, TestStressReorder, TestCustomStream, TestSha256AuthenticationProvider, InfoHandlerTest, ConnectionManagerTest, TestAuthorizationFramework, TestDefaultSearchFieldResource, TestManagedSchemaAPI, TestFieldCacheSortRandom, TestSolrQueryResponse, CoreAdminCreateDiscoverTest, TestSQLHandlerNonCloud, TestFieldCollectionResource, TestSmileRequest, SOLR749Test, ZkStateWriterTest, SuggesterTSTTest, FieldMutatingUpdateProcessorTest, CollectionsAPISolrJTest, VMParamsZkACLAndCredentialsProvidersTest, TestReloadAndDeleteDocs, DOMUtilTest, TestUninvertingReader, SolrCoreCheckLockOnStartupTest, TestFreeTextSuggestions, TestBinaryField, TestMiniSolrCloudCluster, BadComponentTest, TestReloadDeadlock, CachingDirectoryFactoryTest, TestSizeLimitedDistributedMap, TestLeaderInitiatedRecoveryThread, BlockJoinFacetRandomTest, TestLRUStatsCache, HdfsLockFactoryTest, RequestHandlersTest, StatsReloadRaceTest, CheckHdfsIndexTest, HLLSerializationTest, TestBackupRepositoryFactory, SolrPluginUtilsTest, FacetPivotSmallTest, TestLMJelinekMercerSimilarityFactory, TestTolerantUpdateProcessorRandomCloud, SuggesterWFSTTest, TestConfigReload, LukeRequestHandlerTest, DeleteInactiveReplicaTest, MinimalSchemaTest, OverriddenZkACLAndCredentialsProvidersTest, OpenExchangeRatesOrgProviderTest, TestAnalyzeInfixSuggestions, DirectUpdateHandlerOptimizeTest, TestPhraseSuggestions, TestDFISimilarityFactory, TestDistribDocBasedVersion, DistribCursorPagingTest, SolrTestCaseJ4Test, AssignTest, CleanupOldIndexTest, JSONWriterTest, TestGraphMLResponseWriter, TestLFUCache, TestRTimerTree, ExitableDirectoryReaderTest, TestExtendedDismaxParser, DocValuesMissingTest, FieldAnalysisRequestHandlerTest, BinaryUpdateRequestHandlerTest, TermVectorComponentDistributedTest, TestAnalyzedSuggestions, TestDocBasedVersionConstraints, TestCloudRecovery, CollectionStateFormat2Test, TestCloudSchemaless, SimpleCollectionCreateDeleteTest, OverseerTest, ClusterStateUpdateTest, AutoCommitTest, TestDistributedGrouping, TestReload, HardAutoCommitTest, TestRangeQuery, SimpleFacetsTest, StatsComponentTest, TestFiltering, SoftAutoCommitTest, CoreAdminHandlerTest, SuggesterTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, DebugComponentTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, TestIndexingPerformance, IndexSchemaTest, TestCSVResponseWriter, PingRequestHandlerTest, TestElisionMultitermQuery, TestConfig, TestSolrCoreProperties, NotRequiredUniqueKeyTest, SpellPossibilityIteratorTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, TestJmxMonitoredMap, PluginInfoTest, TestSystemIdResolver, PrimUtilsTest, RAMDirectoryFactoryTest, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, CursorPagingTest, TestSimpleTrackingShardHandler, TestEmbeddedSolrServerConstructors, ConnectionReuseTest, BaseCdcrDistributedZkTest, CdcrReplicationDistributedZkTest, CdcrRequestHandlerTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DistribDocExpirationUpdateProcessorTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerModifyCollectionTest, PeerSyncReplicationTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, TestCloudPseudoReturnFields, TestConfigSetsAPIExclusivity, TestHdfsCloudBackupRestore, TestLocalFSCloudBackupRestore, TestReplicaProperties, TestShortCircuitedRequests, TestSolrCloudWithDelegationTokens, TestStressLiveNodes, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsThreadLeakTest, ZkStateReaderTest, RuleEngineTest, RulesTest, TestConfigSetProperties, TestConfigSets, TestDynamicLoading, TestSolrCoreSnapshots, TestBlobHandler, TestReplicationHandlerBackup, TestRestoreCore, CoreMergeIndexesAdminHandlerTest, SecurityConfHandlerTest, DistributedExpandComponentTest, DistributedFacetExistsSmallTest, DistributedFacetPivotLargeTest, DistributedFacetPivotSmallAdvancedTest, DistributedQueryComponentCustomSortTest, TestDistributedStatsComponentCardinality]
   [junit4] Completed [590/646 (1!)] on J2 in 84.58s, 1 test, 1 failure <<< FAILURES!

[...truncated 60568 lines...]