You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/08/20 05:58:33 UTC
[JENKINS] Lucene-Solr-6.x-MacOSX (64bit/jdk1.8.0) - Build # 359 -
Unstable!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/359/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Expected to find shardAddress in the up shard info
Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info
at __randomizedtesting.SeedInfo.seed([4D5F77927E3BCC84:C50B4848D0C7A17C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
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:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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 11687 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/init-core-data-001
[junit4] 2> 2577066 INFO (SUITE-TestDistributedSearch-seed#[4D5F77927E3BCC84]-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-9061) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 2577066 INFO (SUITE-TestDistributedSearch-seed#[4D5F77927E3BCC84]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2580070 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1
[junit4] 2> 2580072 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 2580074 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2ab8283c{/,null,AVAILABLE}
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78386b45{HTTP/1.1,[http/1.1]}{127.0.0.1:57254}
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.Server Started @2586060ms
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=57254, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores}
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control'
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2580075 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/solr.xml
[junit4] 2> 2580096 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores
[junit4] 2> 2580096 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer New CoreContainer 1666488207
[junit4] 2> 2580096 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control]
[junit4] 2> 2580096 WARN (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/lib
[junit4] 2> 2580096 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 2580099 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2580100 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2580100 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2580100 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 2580100 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2580103 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores
[junit4] 2> 2580104 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/, configSet=collection1}
[junit4] 2> 2580104 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1
[junit4] 2> 2580104 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 2580105 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1'
[junit4] 2> 2580105 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2580105 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 2580105 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2580105 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 2580105 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2580105 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2580254 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 2580260 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0
[junit4] 2> 2580275 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2580278 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2580351 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2580356 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 2580357 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 2580359 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 2580361 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 2580362 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2580362 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2580366 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1'
[junit4] 2> 2580366 INFO (coreLoadExecutor-5594-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 2580367 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2580367 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 2580367 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@544f1cb3
[junit4] 2> 2580367 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data/snapshot_metadata
[junit4] 2> 2580367 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 2580367 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data
[junit4] 2> 2580367 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data/index/
[junit4] 2> 2580367 WARN (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2580368 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data/index
[junit4] 2> 2580368 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=19.625, floorSegmentMB=0.71484375, forceMergeDeletesPctAllowed=14.6706560970486, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2580368 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24cb0d8c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7a581a65),segFN=segments_1,generation=1}
[junit4] 2> 2580368 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2580369 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data/
[junit4] 2> 2580369 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/control/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 2580372 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2580372 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2580372 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2580372 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2580372 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2580372 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2580372 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2580373 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2580373 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2580373 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2580373 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2580373 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2580377 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2580377 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2580378 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2580379 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2580382 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2580383 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 2580383 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24cb0d8c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7a581a65),segFN=segments_1,generation=1}
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c44dd93[collection1] main]
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 2580384 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2580412 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2580551 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2580551 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2580552 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2580552 INFO (coreLoadExecutor-5594-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 2580552 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2580552 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2580552 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2580553 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2580553 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2580554 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2580554 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2580554 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2c44dd93[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2580979 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 2580981 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@61f15dd5{/,null,AVAILABLE}
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.ServerConnector Started ServerConnector@708e4d59{HTTP/1.1,[http/1.1]}{127.0.0.1:57255}
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.Server Started @2586969ms
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=57255, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores}
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0'
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2580983 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2580984 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/solr.xml
[junit4] 2> 2581016 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores
[junit4] 2> 2581016 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer New CoreContainer 525259514
[junit4] 2> 2581016 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0]
[junit4] 2> 2581017 WARN (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/lib
[junit4] 2> 2581017 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 2581019 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2581020 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2581020 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2581020 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 2581020 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2581021 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores
[junit4] 2> 2581022 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/, configSet=collection1}
[junit4] 2> 2581022 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 2581022 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 2581023 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1'
[junit4] 2> 2581023 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1
[junit4] 2> 2581023 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2581023 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2581023 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 2581023 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2581023 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2581055 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 2581060 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0
[junit4] 2> 2581074 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2581078 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2581147 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2581152 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 2581152 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 2581156 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 2581157 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 2581158 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2581158 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2581162 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1'
[junit4] 2> 2581162 INFO (coreLoadExecutor-5604-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 2581162 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2581162 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 2581162 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@544f1cb3
[junit4] 2> 2581163 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data/snapshot_metadata
[junit4] 2> 2581163 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 2581163 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data
[junit4] 2> 2581163 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data/index/
[junit4] 2> 2581163 WARN (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2581163 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data/index
[junit4] 2> 2581163 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=19.625, floorSegmentMB=0.71484375, forceMergeDeletesPctAllowed=14.6706560970486, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2581164 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d2c3373 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@235f028d),segFN=segments_1,generation=1}
[junit4] 2> 2581164 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2581164 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data/
[junit4] 2> 2581164 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard0/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 2581168 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2581169 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2581169 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2581169 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2581172 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2581173 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2581174 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2581175 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2581179 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2581180 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 2581180 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2581181 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2581181 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2581181 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2581182 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d2c3373 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@235f028d),segFN=segments_1,generation=1}
[junit4] 2> 2581182 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2581182 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@450c5003[collection1] main]
[junit4] 2> 2581182 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 2581182 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 2581182 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 2581182 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2581212 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2581358 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2581358 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2581359 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2581359 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2581359 INFO (coreLoadExecutor-5604-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 2581359 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2581360 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2581360 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2581360 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2581360 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2581361 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2581362 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@450c5003[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2581531 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2581536 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2581536 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 2581539 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2581539 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 2581547 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 2581550 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 2581555 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 2581560 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 2581563 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 2581566 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 2581569 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 2581572 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 2581575 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 2581578 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 2581580 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 2581582 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 2581585 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 2581587 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 2581589 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 2581592 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 2581595 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 2581598 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 2581600 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2581604 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24cb0d8c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7a581a65),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24cb0d8c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7a581a65),segFN=segments_2,generation=2}
[junit4] 2> 2581604 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 2581606 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@29e5c072[collection1] main]
[junit4] 2> 2581606 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2581607 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@29e5c072[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9)))}
[junit4] 2> 2581607 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 2581609 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2581614 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d2c3373 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@235f028d),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d2c3373 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@235f028d),segFN=segments_2,generation=2}
[junit4] 2> 2581614 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 2581616 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2293074c[collection1] main]
[junit4] 2> 2581616 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2581616 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2293074c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9)))}
[junit4] 2> 2581617 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 2581620 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10]} 0 1
[junit4] 2> 2581623 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 2581626 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 2581628 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 2581631 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 2581633 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 2581636 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 2581638 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 2581641 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001]} 0 0
[junit4] 2> 2581643 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001]} 0 0
[junit4] 2> 2581646 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 2581648 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 2581650 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 2581654 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 2581657 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 2581660 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 2581666 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 2581668 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 2581671 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 2581673 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 2581676 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 2581679 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 2581684 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 2581687 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 2581690 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 2581692 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 2581694 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 2581697 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 2581699 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 2581702 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 2581704 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 2581706 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 2581710 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 2581713 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 2581716 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 2581718 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 2581721 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 2581725 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 2581728 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 2581730 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 2581735 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 2581737 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 2581739 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 2581742 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 2581744 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 2581746 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 2581749 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 2581754 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 2581757 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 2581760 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 2581762 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 2581764 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 2581767 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 2581770 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 2581774 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 2581780 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 2581782 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 2581784 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 2581787 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 2581789 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 2581792 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 2581795 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 2581800 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 2581802 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 2581806 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123]} 0 1
[junit4] 2> 2581808 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 2581811 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 2581814 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 2581820 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 2581822 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 2581824 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 2581826 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 2581830 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127]} 0 0
[junit4] 2> 2581834 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127]} 0 0
[junit4] 2> 2581837 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 2581840 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 2581842 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 2581844 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 2581847 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 2581850 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 2581854 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 2581857 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 2581860 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 2581866 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 2581870 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 2581873 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 2581875 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 2581879 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 2581884 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 2581887 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 2581890 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 2581893 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 2581895 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 2581897 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 2581900 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 2581906 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 2581908 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 2581911 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 2581914 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 2581916 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 2581919 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 2581924 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 2581927 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 2581930 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 2581934 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 2581937 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 2581940 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 2581942 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 2581944 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 2581947 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 2581954 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 2581957 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 2581959 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 2581961 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 2581965 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 2581968 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 2581970 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 2581974 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 2581976 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2581983 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24cb0d8c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7a581a65),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@24cb0d8c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7a581a65),segFN=segments_3,generation=3}
[junit4] 2> 2581983 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 2581984 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@265f96a5[collection1] main]
[junit4] 2> 2581984 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2581984 INFO (searcherExecutor-5595-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@265f96a5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9) Uninverting(_1(6.2.0):C59)))}
[junit4] 2> 2581985 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 2581987 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2581993 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d2c3373 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@235f028d),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d2c3373 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@235f028d),segFN=segments_3,generation=3}
[junit4] 2> 2581993 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 2581994 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b4c974a[collection1] main]
[junit4] 2> 2581994 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2581995 INFO (searcherExecutor-5605-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4b4c974a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.2.0):C9) Uninverting(_1(6.2.0):C59)))}
[junit4] 2> 2581995 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 2581998 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 2581998 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 2582000 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 2582000 INFO (qtp1365270933-11679) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 2582005 INFO (qtp1365270933-11680) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 2582012 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:57255//collection1&rows=10&version=2&q=*:*&NOW=1471669480496&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 2582015 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1471669480496&ids=12,133,111,2,104,5,6,117,109,140&isShard=true&shard.url=http://127.0.0.1:57255//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2582016 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:57255//collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 2582019 INFO (qtp1365270933-11681) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 2582027 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:57255//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1471669480509&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 2582030 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1471669480509&ids=143,135,115,137,127,129,130,141,131,120&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:57255//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2582031 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:57255//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 2582034 INFO (qtp1365270933-11682) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 2582040 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:57255//collection1&rows=10&version=2&q=*:*&NOW=1471669480524&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 2582043 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1471669480524&ids=110,143,100,122,1,13,14,137,16,108&isShard=true&shard.url=http://127.0.0.1:57255//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2582044 INFO (qtp1088592607-11696) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:57255//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 2582046 INFO (qtp1365270933-11677) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 2582051 INFO (qtp1088592607-11699) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:57255//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1471669480536&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 2582055 INFO (qtp1088592607-11694) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1471669480536&ids=145,102,103,147,139,128,107,140,142,131&isShard=true&shard.url=http://127.0.0.1:57255//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2582056 INFO (qtp1088592607-11695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:57255//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 2582060 INFO (qtp1365270933-11675) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 2582066 INFO (qtp1088592607-11697) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:57255//collection1&rows=10&version=2&q=*:*&NOW=1471669480549&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 2582069 INFO (qtp1088592607-11698) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1471669480549&ids=121,100,101,146,113,5,148,104,129,142&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:57255//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2582070 INFO (qtp1088592607-11692) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:57255//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 2582072 INFO (qtp1365270933-11678) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf
[...truncated too long message...]
2696857 INFO (coreCloseExecutor-5647-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard1/cores/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard1/cores/collection1/data;done=false>>]
[junit4] 2> 2696858 INFO (coreCloseExecutor-5647-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2696858 INFO (coreCloseExecutor-5647-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard1/cores/collection1/data
[junit4] 2> 2696858 INFO (coreCloseExecutor-5647-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard1/cores/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard1/cores/collection1/data/index;done=false>>]
[junit4] 2> 2696858 INFO (coreCloseExecutor-5647-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2696858 INFO (coreCloseExecutor-5647-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001/tempDir-001/shard1/cores/collection1/data/index
[junit4] 2> 2696859 INFO (TEST-TestDistributedSearch.test-seed#[4D5F77927E3BCC84]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@18445569{/,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=4D5F77927E3BCC84 -Dtests.slow=true -Dtests.locale=hu-HU -Dtests.timezone=Europe/Sarajevo -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 120s J1 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected to find shardAddress in the up shard info
[junit4] > at __randomizedtesting.SeedInfo.seed([4D5F77927E3BCC84:C50B4848D0C7A17C]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2696867 INFO (SUITE-TestDistributedSearch-seed#[4D5F77927E3BCC84]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4D5F77927E3BCC84-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {other_tl1=PostingsFormat(name=LuceneFixedGap), range_facet_l_dv=PostingsFormat(name=MockRandom), SubjectTerms_mfacet=PostingsFormat(name=LuceneFixedGap), n_l1=PostingsFormat(name=LuceneVarGapFixedInterval), a_n_tdt=PostingsFormat(name=Memory doPackFST= false), multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=PostingsFormat(name=LuceneFixedGap), n_td1=PostingsFormat(name=LuceneFixedGap), n_d1=PostingsFormat(name=LuceneVarGapFixedInterval), n_f1=PostingsFormat(name=LuceneFixedGap), range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), n_tl1=PostingsFormat(name=LuceneFixedGap), oddField_s=PostingsFormat(name=MockRandom), n_tf1=PostingsFormat(name=LuceneVarGapFixedInterval), lowerfilt1and2=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=PostingsFormat(name=LuceneVarGapFixedInterval), lowerfilt=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=MockRandom), text=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=LuceneVarGapFixedInterval), severity=PostingsFormat(name=Memory doPackFST= false), foo_sev_enum=PostingsFormat(name=Memory doPackFST= false), n_dt1=PostingsFormat(name=LuceneFixedGap), a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), b_n_tdt=PostingsFormat(name=LuceneFixedGap), n_ti1=PostingsFormat(name=MockRandom), rnd_b=PostingsFormat(name=MockRandom), foo_b=PostingsFormat(name=Memory doPackFST= false), n_tdt1=PostingsFormat(name=LuceneFixedGap), foo_d=PostingsFormat(name=MockRandom), range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), foo_f=PostingsFormat(name=Memory doPackFST= false)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), foo_sev_enum=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=753, maxMBSortInHeap=6.9861690976965205, sim=ClassicSimilarity, locale=hu-HU, timezone=Europe/Sarajevo
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=132996320,total=470810624
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrIndexConfig, ShardRoutingCustomTest, DateMathParserTest, TestRangeQuery, TestConfigSetProperties, TestTolerantUpdateProcessorRandomCloud, IndexSchemaRuntimeFieldTest, OverseerModifyCollectionTest, ShowFileRequestHandlerTest, DateRangeFieldTest, HdfsChaosMonkeyNothingIsSafeTest, TestSolrJ, VersionInfoTest, ExplicitHLLTest, TestFieldResource, TestOrdValues, TestSuggestSpellingConverter, TestCSVResponseWriter, TestUninvertingReader, HdfsThreadLeakTest, TestExpandComponent, TestRandomFlRTGCloud, TestSolrQueryParser, TestSubQueryTransformerCrossCore, TestPKIAuthenticationPlugin, InfoHandlerTest, TestStressRecovery, TestDynamicFieldResource, TestTestInjection, TestBulkSchemaConcurrent, IgnoreCommitOptimizeUpdateProcessorFactoryTest, HdfsRestartWhileUpdatingTest, TestBinaryResponseWriter, TestLMJelinekMercerSimilarityFactory, TestDynamicLoading, CopyFieldTest, HLLSerializationTest, TestCoreDiscovery, TestReload, TestSolrDeletionPolicy2, ConnectionReuseTest, LoggingHandlerTest, CollectionsAPIAsyncDistributedZkTest, ConvertedLegacyTest, UpdateRequestProcessorFactoryTest, TestMiniSolrCloudClusterBase, TestBlendedInfixSuggestions, NotRequiredUniqueKeyTest, DefaultValueUpdateProcessorTest, ConjunctionSolrSpellCheckerTest, SparseHLLTest, CacheHeaderTest, TestManagedResource, TestInfoStreamLogging, SignatureUpdateProcessorFactoryTest, TestSmileRequest, SharedFSAutoReplicaFailoverUtilsTest, TestGeoJSONResponseWriter, BinaryUpdateRequestHandlerTest, ReplaceNodeTest, BlockJoinFacetRandomTest, SynonymTokenizerTest, RestartWhileUpdatingTest, TestCharFilters, TestHashQParserPlugin, TestRTimerTree, TestScoreJoinQPScore, CollectionsAPIDistributedZkTest, MinimalSchemaTest, SolrXmlInZkTest, TestFieldCacheSanityChecker, HdfsUnloadDistributedZkTest, TestFieldTypeResource, TestFieldCacheSortRandom, TestJettySolrRunner, TolerantUpdateProcessorTest, TestZkChroot, TestManagedSynonymFilterFactory, TestStressUserVersions, CdcrRequestHandlerTest, SuggestComponentTest, TestSolrCloudWithKerberosAlt, TestValueSourceCache, TestAtomicUpdateErrorCases, TestSchemaResource, SpellingQueryConverterTest, TestFastOutputStream, TestRealTimeGet, TestCustomSort, SolrCmdDistributorTest, TestCollationFieldDocValues, ChaosMonkeySafeLeaderTest, BJQParserTest, TestFreeTextSuggestions, SpellCheckComponentTest, TestSha256AuthenticationProvider, TestTrackingShardHandlerFactory, OverriddenZkACLAndCredentialsProvidersTest, SuggesterTest, SortByFunctionTest, TestDistributedGrouping, TestChildDocTransformer, TriLevelCompositeIdRoutingTest, TestSolrQueryParserDefaultOperatorResource, CoreSorterTest, RequestHandlersTest, TestRTGBase, TestQueryUtils, SuggesterTSTTest, TestRandomFaceting, PluginInfoTest, MultiThreadedOCPTest, TestSizeLimitedDistributedMap, TestWriterPerf, TestLRUStatsCache, StatsComponentTest, TestFastWriter, TestConfigOverlay, TestSchemaVersionResource, ResourceLoaderTest, ZkStateReaderTest, OutputWriterTest, TermVectorComponentDistributedTest, TestRequestForwarding, TestPHPSerializedResponseWriter, SpatialHeatmapFacetsTest, TestPerFieldSimilarityWithDefaultOverride, DistributedQueueTest, DistanceUnitsTest, DistributedIntervalFacetingTest, BlockCacheTest, TestSolrConfigHandlerConcurrent, TestSolrFieldCacheMBean, TestSolrCoreProperties, NoCacheHeaderTest, ForceLeaderTest, TestManagedSchemaAPI, SolrPluginUtilsTest, TestSQLHandler, TestSSLRandomization, HdfsCollectionsAPIDistributedZkTest, SolrCLIZkUtilsTest, TestRecoveryHdfs, TestLeaderInitiatedRecoveryThread, AutoCommitTest, CloneFieldUpdateProcessorFactoryTest, TestDefaultStatsCache, CSVRequestHandlerTest, TestFieldCacheWithThreads, TestSolrQueryResponse, SolrCoreCheckLockOnStartupTest, LeaderFailoverAfterPartitionTest, FieldAnalysisRequestHandlerTest, DistributedQueryComponentCustomSortTest, DistanceFunctionTest, NumericFieldsTest, UnloadDistributedZkTest, LeaderElectionIntegrationTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomDVFaceting, TestDistributedSearch]
[junit4] Completed [335/629 (1!)] on J1 in 119.83s, 1 test, 1 failure <<< FAILURES!
[...truncated 61082 lines...]