Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/16617/
Java: 64bit/jdk-9-ea+115 -XX:-UseCompressedOops -XX:+UseG1GC

All tests passed

Build Log:
[...truncated 11963 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/init-core-data-001
   [junit4]   2> 1330538 INFO  
(SUITE-TestDistributedSearch-seed#[83EAB5C7C481F3B6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true)
   [junit4]   2> 1330539 INFO  
(SUITE-TestDistributedSearch-seed#[83EAB5C7C481F3B6]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: 
/crbxf/r
   [junit4]   2> 1330598 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1330599 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 1330600 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2947df20{/crbxf/r,null,AVAILABLE}
   [junit4]   2> 1330601 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.e.j.u.s.SslContextFactory x509=X509@41754f60(solrtest,h=[],w=[]) for 
SslContextFactory@7b80fe9e(file:///home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/etc/test/solrtest.keystore,file:///home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/etc/test/solrtest.keystore)
   [junit4]   2> 1330602 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.e.j.s.ServerConnector Started ServerConnector@6c298011{SSL,[ssl, 
http/1.1]}{127.0.0.1:43624}
   [junit4]   2> 1330603 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] o.e.j.s.Server 
Started @1332733ms
   [junit4]   2> 1330603 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/crbxf/r, 
hostPort=43624, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores}
   [junit4]   2> 1330603 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
jdk.internal.loader.ClassLoaders$AppClassLoader@546a03af
   [junit4]   2> 1330603 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control'
   [junit4]   2> 1330603 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1330603 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1330603 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/solr.xml
   [junit4]   2> 1330606 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores
   [junit4]   2> 1330606 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 12648045
   [junit4]   2> 1330606 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control]
   [junit4]   2> 1330607 WARN  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/lib
 to classpath: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/lib
   [junit4]   2> 1330611 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,
   [junit4]   2> 1330612 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
   [junit4]   2> 1330612 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1330612 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1330612 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1330612 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1330613 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores
   [junit4]   2> 1330613 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
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> 1330613 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1330613 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1330614 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 1330614 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1330614 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1330614 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1330614 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1330614 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1330614 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1330637 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1330643 WARN  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1330645 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1330662 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1330665 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1330742 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1330745 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1330746 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1330749 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1330750 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1330751 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1330751 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1330752 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1330752 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1330752 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1330752 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1330752 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@30f6d19d
   [junit4]   2> 1330753 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1/data
   [junit4]   2> 1330753 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1/data/index/
   [junit4]   2> 1330753 WARN  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1330753 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1/data/index
   [junit4]   2> 1330753 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=810085789, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1330753 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@393dfa71 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@636e9f07),segFN=segments_1,generation=1}
   [junit4]   2> 1330754 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1330754 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.SolrCore 
Looking for old index directories to cleanup for core collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1/data/
   [junit4]   2> 1330754 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    ] 
o.a.s.c.DirectoryFactory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/control/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 1330755 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 1330755 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1330755 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"uniq-fields"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-explicit"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1330756 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating 
implicit default
   [junit4]   2> 1330757 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1330758 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1330758 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1330758 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1330760 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1330761 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
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,/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> 1330761 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1330761 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1330761 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1330770 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1330770 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1330770 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=10, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=92.845703125, 
floorSegmentMB=1.44921875, forceMergeDeletesPctAllowed=2.3358060334208175, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1330771 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@393dfa71 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@636e9f07),segFN=segments_1,generation=1}
   [junit4]   2> 1330771 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1330771 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@64d01035[collection1] main]
   [junit4]   2> 1330771 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1330771 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1330771 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1330771 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1330798 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1330933 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1330933 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1330933 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1330933 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1330933 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1330933 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version 
buckets from index
   [junit4]   2> 1330933 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1330933 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket 
highest value from index
   [junit4]   2> 1330933 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1532976724484030464
   [junit4]   2> 1330934 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1330934 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1330934 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1330934 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1330935 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1330935 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 
1532976724484030464
   [junit4]   2> 1330935 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@64d01035[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1330935 INFO  (coreLoadExecutor-4495-thread-1) [    ] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1331172 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1331173 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 1331174 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@b0b67cb{/crbxf/r,null,AVAILABLE}
   [junit4]   2> 1331175 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.e.j.u.s.SslContextFactory x509=X509@4e898659(solrtest,h=[],w=[]) for 
SslContextFactory@51e9630e(file:///home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/etc/test/solrtest.keystore,file:///home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/etc/test/solrtest.keystore)
   [junit4]   2> 1331176 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.e.j.s.ServerConnector Started ServerConnector@3fce2c5e{SSL,[ssl, 
http/1.1]}{127.0.0.1:41393}
   [junit4]   2> 1331177 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] o.e.j.s.Server 
Started @1333307ms
   [junit4]   2> 1331177 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/crbxf/r, 
hostPort=41393, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores}
   [junit4]   2> 1331177 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
jdk.internal.loader.ClassLoaders$AppClassLoader@546a03af
   [junit4]   2> 1331177 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0'
   [junit4]   2> 1331177 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1331177 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1331177 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1331180 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores
   [junit4]   2> 1331180 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1240235406
   [junit4]   2> 1331181 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0]
   [junit4]   2> 1331181 WARN  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/lib
 to classpath: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/lib
   [junit4]   2> 1331181 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,
   [junit4]   2> 1331182 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
   [junit4]   2> 1331182 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1331182 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1331182 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1331182 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1331183 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores
   [junit4]   2> 1331183 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
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> 1331183 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1331183 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1331183 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 1331184 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1331183 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1331184 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1331184 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1331184 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1331184 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1331198 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1331204 WARN  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1331206 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1331223 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1331226 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1331333 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1331337 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1331338 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1331341 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1331342 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1331343 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1331343 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1331344 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1331344 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1331344 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1331345 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1331345 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@30f6d19d
   [junit4]   2> 1331345 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1/data
   [junit4]   2> 1331345 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1/data/index/
   [junit4]   2> 1331345 WARN  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1331345 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 1331346 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=810085789, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1331347 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3385e920 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43b57660),segFN=segments_1,generation=1}
   [junit4]   2> 1331347 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1331347 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.SolrCore 
Looking for old index directories to cleanup for core collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1/data/
   [junit4]   2> 1331347 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    ] 
o.a.s.c.DirectoryFactory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_83EAB5C7C481F3B6-001/tempDir-001/shard0/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 1331349 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 1331349 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1331349 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"uniq-fields"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-explicit"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1331350 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating 
implicit default
   [junit4]   2> 1331351 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1331352 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1331353 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1331353 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1331356 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1331357 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
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,/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> 1331357 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1331357 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1331357 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1331358 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1331358 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1331359 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=10, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=92.845703125, 
floorSegmentMB=1.44921875, forceMergeDeletesPctAllowed=2.3358060334208175, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1331359 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3385e920 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43b57660),segFN=segments_1,generation=1}
   [junit4]   2> 1331359 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1331360 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@24a0f409[collection1] main]
   [junit4]   2> 1331360 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1331360 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1331360 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1331360 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1331379 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1331497 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1331497 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1331497 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1331497 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1331497 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1331497 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1331498 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1331498 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version 
buckets from index
   [junit4]   2> 1331498 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket 
highest value from index
   [junit4]   2> 1331498 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1532976725076475904
   [junit4]   2> 1331498 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1331498 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1331498 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1331498 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1331499 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@24a0f409[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1331499 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 
1532976725076475904
   [junit4]   2> 1331500 INFO  (coreLoadExecutor-4506-thread-1) [    ] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1331685 INFO  
(TEST-TestDistributedSearch.test-seed#[83EAB5C7C481F3B6]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1331707 INFO  (qtp965763637-10189) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1532976725293531136)} 0 1
   [junit4]   2> 1331729 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1532976725316599808)} 0 2
   [junit4]   2> 1331738 INFO  (qtp965763637-10189) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[1 (1532976725326036992)]} 0 1
   [junit4]   2> 1331749 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[1 (1532976725337571328)]} 0 1
   [junit4]   2> 1331754 INFO  (qtp965763637-10189) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[2 (1532976725344911360)]} 0 0
   [junit4]   2> 1331765 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[2 (1532976725350154240)]} 0 6
   [junit4]   2> 1331771 INFO  (qtp965763637-10189) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[3 (1532976725361688576)]} 0 0
   [junit4]   2> 1331777 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[3 (1532976725367980032)]} 0 0
   [junit4]   2> 1331783 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[4 (1532976725374271488)]} 0 0
   [junit4]   2> 1331789 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[4 (1532976725380562944)]} 0 0
   [junit4]   2> 1331794 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[5 (1532976725386854400)]} 0 0
   [junit4]   2> 1331799 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[5 (1532976725392097280)]} 0 0
   [junit4]   2> 1331805 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[6 (1532976725398388736)]} 0 0
   [junit4]   2> 1331811 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[6 (1532976725404680192)]} 0 0
   [junit4]   2> 1331816 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[7 (1532976725409923072)]} 0 0
   [junit4]   2> 1331823 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[7 (1532976725416214528)]} 0 0
   [junit4]   2> 1331828 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[8 (1532976725422505984)]} 0 0
   [junit4]   2> 1331834 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[8 (1532976725427748864)]} 0 0
   [junit4]   2> 1331839 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[9 (1532976725432991744)]} 0 0
   [junit4]   2> 1331846 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[9 (1532976725441380352)]} 0 0
   [junit4]   2> 1331851 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1331859 INFO  (qtp965763637-10190) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@393dfa71 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@636e9f07),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@393dfa71 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@636e9f07),segFN=segments_2,generation=2}
   [junit4]   2> 1331860 INFO  (qtp965763637-10190) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 1331862 INFO  (qtp965763637-10190) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@9a1277e[collection1] main]
   [junit4]   2> 1331862 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1331862 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@9a1277e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 1331862 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 11
   [junit4]   2> 1331868 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1331872 INFO  (qtp2131600125-10215) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3385e920 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43b57660),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3385e920 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43b57660),segFN=segments_2,generation=2}
   [junit4]   2> 1331873 INFO  (qtp2131600125-10215) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 1331874 INFO  (qtp2131600125-10215) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5e01173b[collection1] main]
   [junit4]   2> 1331874 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1331874 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5e01173b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 1331874 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 1331882 INFO  (qtp965763637-10191) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[10 (1532976725475983360)]} 0 2
   [junit4]   2> 1331889 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[10 (1532976725484371968)]} 0 2
   [junit4]   2> 1331895 INFO  (qtp965763637-10191) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[11 (1532976725491712000)]} 0 0
   [junit4]   2> 1331900 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[11 (1532976725498003456)]} 0 0
   [junit4]   2> 1331906 INFO  (qtp965763637-10191) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[12 (1532976725503246336)]} 0 0
   [junit4]   2> 1331911 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[12 (1532976725509537792)]} 0 0
   [junit4]   2> 1331917 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[13 (1532976725514780672)]} 0 0
   [junit4]   2> 1331923 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[13 (1532976725521072128)]} 0 0
   [junit4]   2> 1331928 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[1001 (1532976725526315008)]} 0 1
   [junit4]   2> 1331934 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[1001 (1532976725532606464)]} 0 0
   [junit4]   2> 1331938 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[14 (1532976725537849344)]} 0 0
   [junit4]   2> 1331944 INFO  (qtp2131600125-10211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[14 (1532976725543092224)]} 0 0
   [junit4]   2> 1331949 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[15 (1532976725548335104)]} 0 0
   [junit4]   2> 1331953 INFO  (qtp2131600125-10211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[15 (1532976725553577984)]} 0 0
   [junit4]   2> 1331958 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[16 (1532976725558820864)]} 0 0
   [junit4]   2> 1331962 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[16 (1532976725563015168)]} 0 0
   [junit4]   2> 1331968 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[17 (1532976725569306624)]} 0 0
   [junit4]   2> 1331972 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[17 (1532976725573500928)]} 0 0
   [junit4]   2> 1331976 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[100 (1532976725576646656)]} 0 0
   [junit4]   2> 1331979 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[100 (1532976725579792384)]} 0 0
   [junit4]   2> 1331982 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[101 (1532976725583986688)]} 0 0
   [junit4]   2> 1331984 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[101 (1532976725586083840)]} 0 0
   [junit4]   2> 1331987 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[102 (1532976725589229568)]} 0 0
   [junit4]   2> 1331990 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[102 (1532976725592375296)]} 0 0
   [junit4]   2> 1331993 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[103 (1532976725595521024)]} 0 0
   [junit4]   2> 1331996 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[103 (1532976725597618176)]} 0 0
   [junit4]   2> 1331998 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[104 (1532976725600763904)]} 0 0
   [junit4]   2> 1332002 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[104 (1532976725603909632)]} 0 0
   [junit4]   2> 1332004 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[105 (1532976725607055360)]} 0 0
   [junit4]   2> 1332007 INFO  (qtp2131600125-10213) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[105 (1532976725610201088)]} 0 0
   [junit4]   2> 1332010 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[106 (1532976725612298240)]} 0 0
   [junit4]   2> 1332013 INFO  (qtp2131600125-10213) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[106 (1532976725616492544)]} 0 0
   [junit4]   2> 1332016 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[107 (1532976725619638272)]} 0 0
   [junit4]   2> 1332019 INFO  (qtp2131600125-10213) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[107 (1532976725622784000)]} 0 0
   [junit4]   2> 1332022 INFO  (qtp965763637-10189) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[108 (1532976725625929728)]} 0 0
   [junit4]   2> 1332026 INFO  (qtp2131600125-10213) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[108 (1532976725630124032)]} 0 0
   [junit4]   2> 1332030 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[109 (1532976725633269760)]} 0 0
   [junit4]   2> 1332033 INFO  (qtp2131600125-10213) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[109 (1532976725637464064)]} 0 0
   [junit4]   2> 1332037 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[110 (1532976725640609792)]} 0 1
   [junit4]   2> 1332040 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[110 (1532976725643755520)]} 0 0
   [junit4]   2> 1332042 INFO  (qtp965763637-10191) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[111 (1532976725646901248)]} 0 0
   [junit4]   2> 1332045 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[111 (1532976725650046976)]} 0 0
   [junit4]   2> 1332048 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[112 (1532976725653192704)]} 0 0
   [junit4]   2> 1332051 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[112 (1532976725656338432)]} 0 0
   [junit4]   2> 1332054 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[113 (1532976725659484160)]} 0 0
   [junit4]   2> 1332057 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[113 (1532976725662629888)]} 0 0
   [junit4]   2> 1332061 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[114 (1532976725666824192)]} 0 0
   [junit4]   2> 1332064 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[114 (1532976725668921344)]} 0 0
   [junit4]   2> 1332067 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[115 (1532976725672067072)]} 0 0
   [junit4]   2> 1332070 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[115 (1532976725676261376)]} 0 0
   [junit4]   2> 1332074 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[116 (1532976725680455680)]} 0 0
   [junit4]   2> 1332078 INFO  (qtp2131600125-10209) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[116 (1532976725683601408)]} 0 0
   [junit4]   2> 1332081 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[117 (1532976725687795712)]} 0 0
   [junit4]   2> 1332085 INFO  (qtp2131600125-10211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[117 (1532976725690941440)]} 0 1
   [junit4]   2> 1332088 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[118 (1532976725695135744)]} 0 0
   [junit4]   2> 1332092 INFO  (qtp2131600125-10211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[118 (1532976725698281472)]} 0 0
   [junit4]   2> 1332095 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[119 (1532976725702475776)]} 0 0
   [junit4]   2> 1332100 INFO  (qtp2131600125-10211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[119 (1532976725706670080)]} 0 0
   [junit4]   2> 1332103 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[120 (1532976725710864384)]} 0 0
   [junit4]   2> 1332106 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[120 (1532976725714010112)]} 0 0
   [junit4]   2> 1332111 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[121 (1532976725719252992)]} 0 0
   [junit4]   2> 1332115 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[121 (1532976725722398720)]} 0 0
   [junit4]   2> 1332118 INFO  (qtp965763637-10189) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[122 (1532976725726593024)]} 0 0
   [junit4]   2> 1332122 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[122 (1532976725730787328)]} 0 0
   [junit4]   2> 1332126 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[123 (1532976725733933056)]} 0 0
   [junit4]   2> 1332129 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[123 (1532976725738127360)]} 0 0
   [junit4]   2> 1332133 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[124 (1532976725742321664)]} 0 0
   [junit4]   2> 1332137 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[124 (1532976725746515968)]} 0 0
   [junit4]   2> 1332140 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[125 (1532976725749661696)]} 0 0
   [junit4]   2> 1332143 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[125 (1532976725752807424)]} 0 0
   [junit4]   2> 1332147 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[126 (1532976725757001728)]} 0 0
   [junit4]   2> 1332164 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[126 (1532976725773778944)]} 0 0
   [junit4]   2> 1332169 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[127 (1532976725780070400)]} 0 0
   [junit4]   2> 1332173 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[127 (1532976725783216128)]} 0 0
   [junit4]   2> 1332176 INFO  (qtp965763637-10195) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[128 (1532976725787410432)]} 0 0
   [junit4]   2> 1332179 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[128 (1532976725790556160)]} 0 0
   [junit4]   2> 1332183 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[129 (1532976725794750464)]} 0 0
   [junit4]   2> 1332186 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[129 (1532976725797896192)]} 0 0
   [junit4]   2> 1332190 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[130 (1532976725802090496)]} 0 0
   [junit4]   2> 1332194 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[130 (1532976725805236224)]} 0 0
   [junit4]   2> 1332197 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[131 (1532976725809430528)]} 0 0
   [junit4]   2> 1332201 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[131 (1532976725812576256)]} 0 0
   [junit4]   2> 1332205 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[132 (1532976725817819136)]} 0 0
   [junit4]   2> 1332210 INFO  (qtp2131600125-10211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[132 (1532976725822013440)]} 0 0
   [junit4]   2> 1332213 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[133 (1532976725826207744)]} 0 0
   [junit4]   2> 1332219 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[133 (1532976725831450624)]} 0 0
   [junit4]   2> 1332223 INFO  (qtp965763637-10192) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[134 (1532976725835644928)]} 0 0
   [junit4]   2> 1332226 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[134 (1532976725838790656)]} 0 0
   [junit4]   2> 1332229 INFO  (qtp965763637-10191) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[135 (1532976725842984960)]} 0 0
   [junit4]   2> 1332232 INFO  (qtp2131600125-10211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[135 (1532976725846130688)]} 0 0
   [junit4]   2> 1332236 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[136 (1532976725849276416)]} 0 0
   [junit4]   2> 1332240 INFO  (qtp2131600125-10214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[136 (1532976725853470720)]} 0 1
   [junit4]   2> 1332244 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[137 (1532976725858713600)]} 0 0
   [junit4]   2> 1332248 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[137 (1532976725861859328)]} 0 0
   [junit4]   2> 1332251 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[138 (1532976725866053632)]} 0 0
   [junit4]   2> 1332254 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[138 (1532976725869199360)]} 0 0
   [junit4]   2> 1332258 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[139 (1532976725873393664)]} 0 0
   [junit4]   2> 1332262 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[139 (1532976725877587968)]} 0 0
   [junit4]   2> 1332266 INFO  (qtp965763637-10191) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[140 (1532976725880733696)]} 0 0
   [junit4]   2> 1332269 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[140 (1532976725883879424)]} 0 0
   [junit4]   2> 1332271 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[141 (1532976725887025152)]} 0 0
   [junit4]   2> 1332274 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[141 (1532976725890170880)]} 0 0
   [junit4]   2> 1332278 INFO  (qtp965763637-10189) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[142 (1532976725893316608)]} 0 0
   [junit4]   2> 1332281 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[142 (1532976725896462336)]} 0 0
   [junit4]   2> 1332284 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[143 (1532976725899608064)]} 0 0
   [junit4]   2> 1332287 INFO  (qtp2131600125-10208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[143 (1532976725902753792)]} 0 0
   [junit4]   2> 1332291 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[144 (1532976725907996672)]} 0 0
   [junit4]   2> 1332294 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[144 (1532976725911142400)]} 0 0
   [junit4]   2> 1332298 INFO  (qtp965763637-10191) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[145 (1532976725914288128)]} 0 0
   [junit4]   2> 1332301 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[145 (1532976725918482432)]} 0 0
   [junit4]   2> 1332304 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[146 (1532976725921628160)]} 0 0
   [junit4]   2> 1332306 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[146 (1532976725923725312)]} 0 0
   [junit4]   2> 1332309 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[147 (1532976725926871040)]} 0 0
   [junit4]   2> 1332313 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[147 (1532976725930016768)]} 0 0
   [junit4]   2> 1332316 INFO  (qtp965763637-10194) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[148 (1532976725933162496)]} 0 0
   [junit4]   2> 1332320 INFO  (qtp2131600125-10215) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[148 (1532976725938405376)]} 0 0
   [junit4]   2> 1332323 INFO  (qtp965763637-10190) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[149 (1532976725941551104)]} 0 0
   [junit4]   2> 1332327 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={wt=javabin&version=2}{add=[149 (1532976725945745408)]} 0 0
   [junit4]   2> 1332331 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1332351 INFO  (qtp965763637-10196) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@393dfa71 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@636e9f07),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@393dfa71 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@636e9f07),segFN=segments_3,generation=3}
   [junit4]   2> 1332351 INFO  (qtp965763637-10196) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 1332351 INFO  (qtp965763637-10196) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@c2e0231[collection1] main]
   [junit4]   2> 1332352 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1332352 INFO  (searcherExecutor-4496-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@c2e0231[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 1332352 INFO  (qtp965763637-10196) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 21
   [junit4]   2> 1332355 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1332376 INFO  (qtp2131600125-10210) [    ] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3385e920 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43b57660),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3385e920 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43b57660),segFN=segments_3,generation=3}
   [junit4]   2> 1332376 INFO  (qtp2131600125-10210) [    ] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 1332376 INFO  (qtp2131600125-10210) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b783f56[collection1] main]
   [junit4]   2> 1332377 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1332378 INFO  (searcherExecutor-4507-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1b783f56[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 1332378 INFO  (qtp2131600125-10210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/crbxf/r path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 22
   [junit4]   2> 1332381 INFO  (qtp965763637-10194) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1332382 INFO  (qtp965763637-10194) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1332386 INFO  (qtp965763637-10191) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1332386 INFO  (qtp965763637-10191) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1332390 INFO  (qtp965763637-10195) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1337406 INFO  (qtp2131600125-10209) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=https://127.0.0.1:41393/crbxf/r/collection1|[ff01::213]:33332/crbxf/r&rows=10&version=2&q=*:*&NOW=1461960531250&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1337410 INFO  (qtp2131600125-10215) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1461960531250&ids=143,121,100,101,102,15,116,105,138,108&isShard=true&shard.url=https://127.0.0.1:41393/crbxf/r/collection1|[ff01::213]:33332/crbxf/r&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1337415 INFO  (qtp2131600125-10211) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=https://127.0.0.1:41393/crbxf/r/collection1|[ff01::213]:33332/crbxf/r&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5020
   [junit4]   2> 1337415 WARN  (qtp2131600125-10211) [    ] o.a.s.c.SolrCore 
slow: [collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=https://127.0.0.1:41393/crbxf/r/collection1|[ff01::213]:33332/crbxf/r&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5020
   [junit4]   2> 1337418 INFO  (qtp965763637-10190) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1337426 INFO  (qtp2131600125-10210) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/crbxf/r|[ff01::213]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&rows=10&version=2&q=*:*&NOW=1461960536277&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1337429 INFO  (qtp2131600125-10210) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1461960536277&ids=1,2,146,3,115,104,137,5,118,109&isShard=true&shard.url=[ff01::114]:33332/crbxf/r|[ff01::213]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1337430 INFO  (qtp2131600125-10215) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=[ff01::114]:33332/crbxf/r|[ff01::213]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 1337434 INFO  (qtp965763637-10190) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1342447 INFO  (qtp2131600125-10210) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/crbxf/r|[ff01::083]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&rows=10&version=2&q=*:*&NOW=1461960536294&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1342449 INFO  (qtp2131600125-10210) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1461960536294&ids=132,143,122,1,133,124,136,17,129,109&isShard=true&shard.url=[ff01::213]:33332/crbxf/r|[ff01::083]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1342450 INFO  (qtp2131600125-10215) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=[ff01::213]:33332/crbxf/r|[ff01::083]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5012
   [junit4]   2> 1342450 WARN  (qtp2131600125-10215) [    ] o.a.s.c.SolrCore 
slow: [collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=[ff01::213]:33332/crbxf/r|[ff01::083]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5012
   [junit4]   2> 1342454 INFO  (qtp965763637-10190) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1342459 INFO  (qtp2131600125-10211) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::114]:33332/crbxf/r|[ff01::083]:33332/crbxf/r|[ff01::213]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&rows=10&version=2&q=*:*&NOW=1461960541313&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1342461 INFO  (qtp2131600125-10214) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1461960541313&ids=11,13,134,145,5,116,105,138,107,108&isShard=true&shard.url=[ff01::114]:33332/crbxf/r|[ff01::083]:33332/crbxf/r|[ff01::213]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1342465 INFO  (qtp2131600125-10215) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=[ff01::114]:33332/crbxf/r|[ff01::083]:33332/crbxf/r|[ff01::213]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 1342468 INFO  (qtp965763637-10190) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1342473 INFO  (qtp2131600125-10209) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=https://127.0.0.1:41393/crbxf/r/collection1&rows=10&version=2&q=*:*&NOW=1461960541327&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1342475 INFO  (qtp2131600125-10209) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1461960541327&ids=1,124,102,15,114,126,149,105,118,141&isShard=true&shard.url=https://127.0.0.1:41393/crbxf/r/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1342476 INFO  (qtp2131600125-10215) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=https://127.0.0.1:41393/crbxf/r/collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 1342479 INFO  (qtp965763637-10190) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1342483 INFO  (qtp2131600125-10208) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=https://127.0.0.1:41393/crbxf/r/collection1&rows=10&version=2&q=*:*&NOW=1461960541337&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1342485 INFO  (qtp2131600125-10208) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1461960541337&ids=111,100,101,146,3,136,16,1001,128,10&isShard=true&shard.url=https://127.0.0.1:41393/crbxf/r/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1342486 INFO  (qtp2131600125-10215) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&shards=https://127.0.0.1:41393/crbxf/r/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 1342488 INFO  (qtp965763637-10190) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1342493 INFO  (qtp2131600125-10210) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::114]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1|[ff01::083]:33332/crbxf/r&rows=10&version=2&q=*:*&NOW=1461960541347&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1342495 INFO  (qtp2131600125-10214) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/crbxf/r path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1461960541347&ids=143,122,12,2,124,136,17,1001,117,139&isShard=true&shard.url=[ff01::114]:33332/crbxf/r|https://127.0.0.1:41393/crbxf/r/collection1|[ff01::083]:33332/crbxf/r&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1342497 INFO  (qtp2131600125-10215) [    ] 

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

tor.java:1143)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@9-ea/ThreadPoolExecutor.java:632)
   [junit4]    >         at java.lang.Thread.run(java.base@9-ea/Thread.java:804)
   [junit4]    >    9) Thread[id=10196, name=qtp965763637-10196, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    >         at 
sun.nio.ch.EPollArrayWrapper.interrupt(java.base@9-ea/Native Method)
   [junit4]    >         at 
sun.nio.ch.EPollArrayWrapper.interrupt(java.base@9-ea/EPollArrayWrapper.java:317)
   [junit4]    >         at 
sun.nio.ch.EPollSelectorImpl.wakeup(java.base@9-ea/EPollSelectorImpl.java:206)
   [junit4]    >         at 
java.nio.channels.spi.AbstractSelector$1.interrupt(java.base@9-ea/AbstractSelector.java:213)
   [junit4]    >         at 
java.nio.channels.spi.AbstractSelector.begin(java.base@9-ea/AbstractSelector.java:219)
   [junit4]    >         at 
sun.nio.ch.EPollSelectorImpl.doSelect(java.base@9-ea/EPollSelectorImpl.java:91)
   [junit4]    >         at 
sun.nio.ch.SelectorImpl.lockAndDoSelect(java.base@9-ea/SelectorImpl.java:86)
   [junit4]    >         at 
sun.nio.ch.SelectorImpl.select(java.base@9-ea/SelectorImpl.java:97)
   [junit4]    >         at 
sun.nio.ch.SelectorImpl.select(java.base@9-ea/SelectorImpl.java:101)
   [junit4]    >         at 
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:233)
   [junit4]    >         at 
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:181)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:171)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(java.base@9-ea/Thread.java:804)
   [junit4]    >   10) Thread[id=10232, name=qtp2131600125-10232, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    >         at sun.nio.ch.IOUtil.drain(java.base@9-ea/Native 
Method)
   [junit4]    >         at 
sun.nio.ch.EPollSelectorImpl.doSelect(java.base@9-ea/EPollSelectorImpl.java:103)
   [junit4]    >         at 
sun.nio.ch.SelectorImpl.lockAndDoSelect(java.base@9-ea/SelectorImpl.java:86)
   [junit4]    >         at 
sun.nio.ch.SelectorImpl.select(java.base@9-ea/SelectorImpl.java:97)
   [junit4]    >         at 
sun.nio.ch.SelectorImpl.select(java.base@9-ea/SelectorImpl.java:101)
   [junit4]    >         at 
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:233)
   [junit4]    >         at 
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:181)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:171)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(java.base@9-ea/Thread.java:804)
   [junit4]    >   11) Thread[id=10224, name=searcherExecutor-4507-thread-1, 
state=WAITING, group=TGRP-TestDistributedSearch]
   [junit4]    >         at jdk.internal.misc.Unsafe.park(java.base@9-ea/Native 
Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(java.base@9-ea/LockSupport.java:190)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@9-ea/AbstractQueuedSynchronizer.java:2064)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(java.base@9-ea/LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(java.base@9-ea/ThreadPoolExecutor.java:1083)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@9-ea/ThreadPoolExecutor.java:1143)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@9-ea/ThreadPoolExecutor.java:632)
   [junit4]    >         at java.lang.Thread.run(java.base@9-ea/Thread.java:804)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([83EAB5C7C481F3B6]:0)Throwable #5: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=10190, name=qtp965763637-10190, state=RUNNABLE, 
group=TGRP-TestDistributedSearch]
   [junit4]    > Caused by: java.lang.OutOfMemoryError: Java heap 
spaceThrowable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: 
Captured an uncaught exception in thread: Thread[id=10231, 
name=qtp2131600125-10231, state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    > Caused by: java.lang.OutOfMemoryError: Java heap 
spaceThrowable #7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: 
Captured an uncaught exception in thread: Thread[id=10195, 
name=qtp965763637-10195, state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    > Caused by: java.lang.OutOfMemoryError: Java heap 
spaceThrowable #8: com.carrotsearch.randomizedtesting.UncaughtExceptionError: 
Captured an uncaught exception in thread: Thread[id=10213, 
name=qtp2131600125-10213, state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    > Caused by: java.lang.OutOfMemoryError: Java heap space
   [junit4] Completed [440/592 (1!)] on J0 in 744.88s, 1 test, 8 errors <<< 
FAILURES!

[...truncated 481 lines...]
   [junit4] JVM J0: stdout was not empty, see: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20160429_194636_691.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: Java heap space
   [junit4] Dumping heap to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/heapdumps/java_pid15844.hprof 
...
   [junit4] Heap dump file created [551915651 bytes in 3.628 secs]
   [junit4] <<< JVM J0: EOF ----

   [junit4] JVM J0: stderr was not empty, see: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20160429_194636_691.syserr
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "qtp2131600125-10209"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "qtp2131600125-10211"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread 
"org.eclipse.jetty.server.session.HashSessionManager@6e568c14Timer"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "Connection evictor"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "Connection evictor"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "Connection evictor"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "Connection evictor"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread 
"org.eclipse.jetty.server.session.HashSessionManager@2d82be7eTimer"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "Connection evictor"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "Connection evictor"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "Scheduler-1953369373"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "aliveCheckExecutor-1308-thread-1"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "qtp965763637-10194"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "qtp2131600125-10215"
   [junit4] 
   [junit4] Exception: java.lang.OutOfMemoryError thrown from the 
UncaughtExceptionHandler in thread "qtp2131600125-10208"
   [junit4] <<< JVM J0: EOF ----

[...truncated 16 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:740: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:684: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:233: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:529: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1457: 
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1014: 
There were test failures: 592 suites (10 ignored), 2394 tests, 8 suite-level 
errors, 516 ignored (70 assumptions) [seed: 83EAB5C7C481F3B6]

Total time: 64 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to