You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/10/15 18:27:19 UTC

[JENKINS] Lucene-Solr-trunk-Solaris (64bit/jdk1.8.0) - Build # 125 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Solaris/125/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC

No tests ran.

Build Log:
[...truncated 10389 lines...]
   [junit4] Suite: org.apache.solr.core.TestArbitraryIndexDir
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/init-core-data-001
   [junit4]   2> 1940292 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1940297 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testLoadNewIndexDir
   [junit4]   2> 1940298 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1940298 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1940298 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1940299 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1940299 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1940328 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1940334 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1940349 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1940349 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema Reading Solr Schema from /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/schema12.xml
   [junit4]   2> 1940353 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1940467 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1940468 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1940469 WARN  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 1940477 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1940479 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'solr/'
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer New CoreContainer 2074986778
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=solr/]
   [junit4]   2> 1940482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer loading shared library: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/solr/lib
   [junit4]   2> 1940482 WARN  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/solr/lib).
   [junit4]   2> 1940513 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1940516 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1940517 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1940517 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1940517 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1940517 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1940518 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema12.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=collection1, collection=collection1, absoluteInstDir=solr/collection1/, dataDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001, shard=shard1}
   [junit4]   2> 1940519 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'solr/collection1/'
   [junit4]   2> 1940519 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1940519 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1940550 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1940563 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1940579 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1940580 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.s.IndexSchema Reading Solr Schema from solr/collection1/conf/schema12.xml
   [junit4]   2> 1940585 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1940715 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1940716 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1940717 WARN  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 1940737 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1940741 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1940745 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/solr/collection1/conf'
   [junit4]   2> 1940745 INFO  (coreLoadExecutor-5287-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir solr/collection1/
   [junit4]   2> 1940745 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockFSDirectoryFactory
   [junit4]   2> 1940745 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [solr/collection1/], dataDir=[null]
   [junit4]   2> 1940745 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@571dca41
   [junit4]   2> 1940746 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001
   [junit4]   2> 1940746 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index/
   [junit4]   2> 1940746 WARN  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index' doesn't exist. Creating new index...
   [junit4]   2> 1940747 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index
   [junit4]   2> 1940747 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=36.0458984375, floorSegmentMB=2.1962890625, forceMergeDeletesPctAllowed=23.010532224608163, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1940801 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(MMapDirectory@/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c48c5d8),segFN=segments_1,generation=1}
   [junit4]   2> 1940801 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1940802 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1940805 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1940806 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1940806 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1940806 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1940806 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1940807 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1940808 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1940810 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1940811 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1940820 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1940822 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1940822 WARN  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1940822 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1940823 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1940823 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1940824 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=5.4091796875, floorSegmentMB=0.20703125, forceMergeDeletesPctAllowed=26.38900185370003, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1940825 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(MMapDirectory@/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c48c5d8),segFN=segments_1,generation=1}
   [junit4]   2> 1940825 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1940825 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2e613d6f[collection1] main
   [junit4]   2> 1940825 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1940825 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1940825 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1940825 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1940856 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1941069 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1941069 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1941069 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1941069 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1941069 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1941070 INFO  (coreLoadExecutor-5287-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1941070 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1941070 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1941071 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1941071 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1941072 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1941072 INFO  (searcherExecutor-5288-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e613d6f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1941072 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1941176 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1941273 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(MMapDirectory@/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c48c5d8),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(MMapDirectory@/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c48c5d8),segFN=segments_2,generation=2}
   [junit4]   2> 1941274 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1941277 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrCore New index directory detected: old=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index/ new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index_temp
   [junit4]   2> 1941279 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@3ab25e51[collection1] main
   [junit4]   2> 1941279 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1941281 INFO  (searcherExecutor-5288-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ab25e51[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1)))}
   [junit4]   2> 1941282 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 105
   [junit4]   2> 1941282 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'solr/collection1/'
   [junit4]   2> 1941283 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1941283 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1941313 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1941332 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1941347 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1941349 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema Reading Solr Schema from solr/collection1/conf/schema12.xml
   [junit4]   2> 1941353 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1941423 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1941424 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1941424 WARN  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 1941439 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1941442 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1941446 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/solr/collection1/conf'
   [junit4]   2> 1941446 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir solr/collection1/
   [junit4]   2> 1941447 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [solr/collection1/], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/]
   [junit4]   2> 1941448 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@571dca41
   [junit4]   2> 1941448 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index_temp
   [junit4]   2> 1941449 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/
   [junit4]   2> 1941450 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.DirectoryFactory Found 1 old index directories to clean-up under /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/
   [junit4]   2> 1941450 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Cannot delete directory /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index as it is still being referenced in the cache!
   [junit4]   2> 1941450 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.DirectoryFactory Delete old index directory /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index failed.
   [junit4]   2> 1941454 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1941454 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1941454 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1941454 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1941454 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1941454 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1941454 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1941461 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1941461 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1941461 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1941461 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1941461 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1941464 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1941466 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1941467 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1941469 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1941470 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index_temp
   [junit4]   2> 1941477 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1941480 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1941480 WARN  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1941481 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1941482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1941482 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1941484 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@68e31379[collection1] main
   [junit4]   2> 1941484 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1941484 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1941484 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1941485 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1941486 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1941492 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1941492 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1941493 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1941493 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1941493 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1941493 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1941494 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1941494 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1941494 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1
   [junit4]   2> 1941494 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1941495 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1941495 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=77.7158203125, floorSegmentMB=0.4267578125, forceMergeDeletesPctAllowed=29.431547748544347, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1941496 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68e31379[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1)))}
   [junit4]   2> 1941498 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index_temp lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11f8f750),segFN=segments_1,generation=1}
   [junit4]   2> 1941498 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1941498 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1941500 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@79d958da[collection1] main
   [junit4]   2> 1941500 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.CoreContainer replacing core: collection1
   [junit4]   2> 1941500 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@74e438bf
   [junit4]   2> 1941501 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1941501 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1941518 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0 
   [junit4]   2> 1941525 ERROR (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=*[count(//doc)=1]
   [junit4]   2> 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2> 	request was:q=id:2&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 1941525 ERROR (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:2&qt=standard&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=*[count(//doc)=1]
   [junit4]   2> 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2> 	request was:q=id:2&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:759)
   [junit4]   2> 	at org.apache.solr.core.TestArbitraryIndexDir.testLoadNewIndexDir(TestArbitraryIndexDir.java:128)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:497)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:873)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1941526 INFO  (TEST-TestArbitraryIndexDir.testLoadNewIndexDir-seed#[3BC2B9612E5788EA]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testLoadNewIndexDir
   [junit4]   2> 1941530 INFO  (searcherExecutor-5292-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@79d958da[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1)))}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestArbitraryIndexDir -Dtests.method=testLoadNewIndexDir -Dtests.seed=3BC2B9612E5788EA -Dtests.slow=true -Dtests.locale=es_PE -Dtests.timezone=MIT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   1.24s J1 | TestArbitraryIndexDir.testLoadNewIndexDir <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3BC2B9612E5788EA:D2980259B0CE1842]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:766)
   [junit4]    > 	at org.apache.solr.core.TestArbitraryIndexDir.testLoadNewIndexDir(TestArbitraryIndexDir.java:128)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=*[count(//doc)=1]
   [junit4]    > 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
   [junit4]    > </response>
   [junit4]    > 	request was:q=id:2&qt=standard&start=0&rows=20&version=2.2
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:759)
   [junit4]    > 	... 40 more
   [junit4]   2> 1941533 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1941534 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2074986778
   [junit4]   2> 1941534 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@19a5b48e
   [junit4]   2> 1941534 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1941534 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 1941534 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1941535 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1941536 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1941567 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockFSDirectoryFactory - 3 directories currently being tracked
   [junit4]   2> 1941567 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index_temp [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index_temp;done=false>>]
   [junit4]   2> 1941567 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index_temp
   [junit4]   2> 1941571 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001 [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001;done=false>>]
   [junit4]   2> 1941571 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001
   [junit4]   2> 1941572 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index;done=false>>]
   [junit4]   2> 1941572 INFO  (SUITE-TestArbitraryIndexDir-seed#[3BC2B9612E5788EA]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001/tempDir-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.core.TestArbitraryIndexDir_3BC2B9612E5788EA-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=es_PE, timezone=MIT
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_60 (64-bit)/cpus=3,threads=1,free=82910936,total=427819008
   [junit4]   2> NOTE: All tests run in this JVM: [SynonymTokenizerTest, NotRequiredUniqueKeyTest, CollectionsAPIDistributedZkTest, TestStressUserVersions, TestCharFilters, TestManagedSchemaFieldResource, OpenCloseCoreStressTest, SuggesterTest, SecurityConfHandlerTest, TestFoldingMultitermQuery, TestSolr4Spatial, TestRandomMergePolicy, RemoteQueryErrorTest, DebugComponentTest, DirectUpdateHandlerOptimizeTest, ChaosMonkeySafeLeaderTest, TestBadConfig, TestHdfsUpdateLog, TermVectorComponentTest, DocValuesMissingTest, HdfsRecoveryZkTest, BigEndianAscendingWordSerializerTest, SearchHandlerTest, TestRandomRequestDistribution, TestReversedWildcardFilterFactory, ZkSolrClientTest, TestSolrXml, DeleteLastCustomShardedReplicaTest, TestExceedMaxTermLength, TestCollapseQParserPlugin, TestRandomFaceting, TestInfoStreamLogging, HighlighterConfigTest, TestCloudManagedSchema, PreAnalyzedFieldTest, SolrCmdDistributorTest, ResponseHeaderTest, DistanceUnitsTest, SuggestComponentTest, TestSortingResponseWriter, TestPivotHelperCode, CoreAdminCreateDiscoverTest, DistributedVersionInfoTest, TestIntervalFaceting, DistributedQueryComponentOptimizationTest, BufferStoreTest, ShardRoutingCustomTest, HdfsWriteToMultipleCollectionsTest, TestCollectionAPI, TestRangeQuery, TestReloadAndDeleteDocs, CloudExitableDirectoryReaderTest, TestSolrConfigHandler, BasicDistributedZk2Test, StatsComponentTest, TestFieldTypeCollectionResource, ShardSplitTest, SuggesterTSTTest, TestConfigOverlay, TestPKIAuthenticationPlugin, CdcrVersionReplicationTest, TestRealTimeGet, TestRequestStatusCollectionAPI, RequestHandlersTest, HdfsThreadLeakTest, PrimUtilsTest, BJQParserTest, CopyFieldTest, DistributedIntervalFacetingTest, QueryParsingTest, SuggesterWFSTTest, LeaderInitiatedRecoveryOnCommitTest, BasicZkTest, CollectionReloadTest, AutoCommitTest, TestInitQParser, TestFreeTextSuggestions, TestDefaultSearchFieldResource, HdfsBasicDistributedZkTest, LukeRequestHandlerTest, SolrTestCaseJ4Test, ConcurrentDeleteAndCreateCollectionTest, SpellPossibilityIteratorTest, SharedFSAutoReplicaFailoverTest, BlockCacheTest, UniqFieldsUpdateProcessorFactoryTest, TestCryptoKeys, TestOmitPositions, TestDistribIDF, DistributedFacetPivotSmallTest, TestSerializedLuceneMatchVersion, DistributedFacetPivotLongTailTest, TestBulkSchemaAPI, TestTrie, TestReqParamsAPI, ConnectionManagerTest, TestFieldResource, TestFieldTypeResource, TestRecoveryHdfs, FieldAnalysisRequestHandlerTest, TestFiltering, DistributedTermsComponentTest, TestQueryTypes, TestSchemaSimilarityResource, TestSQLHandler, SolrIndexSplitterTest, MultiThreadedOCPTest, TestStressRecovery, TestDownShardTolerantSearch, CdcrRequestHandlerTest, TestCloudInspectUtil, TestObjectReleaseTracker, BinaryUpdateRequestHandlerTest, TestSolrCoreProperties, TestUtils, TestFuzzyAnalyzedSuggestions, TestReplicationHandlerBackup, TestSolrDeletionPolicy2, AnalysisErrorHandlingTest, TestCollationField, TestSolrQueryParser, TestJsonRequest, CollectionStateFormat2Test, TestReload, TestDistributedMissingSort, TestZkChroot, HdfsSyncSliceTest, HdfsChaosMonkeyNothingIsSafeTest, ShowFileRequestHandlerTest, SolrInfoMBeanTest, TestIndexSearcher, CloudMLTQParserTest, TestPHPSerializedResponseWriter, TestJoin, HardAutoCommitTest, SimpleFacetsTest, TestGroupingSearch, PeerSyncTest, BadIndexSchemaTest, BasicFunctionalityTest, CurrencyFieldOpenExchangeTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, CoreAdminHandlerTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest, QueryEqualityTest, TestRemoteStreaming, DisMaxRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, TestArbitraryIndexDir]
   [junit4] Completed [341/552] on J1 in 1.34s, 1 test, 1 error <<< FAILURES!

[...truncated 290 lines...]
   [junit4] Suite: org.apache.solr.cloud.SolrCloudExampleTest
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/init-core-data-001
   [junit4]   2> 2343814 INFO  (SUITE-SolrCloudExampleTest-seed#[3BC2B9612E5788EA]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 2343814 INFO  (SUITE-SolrCloudExampleTest-seed#[3BC2B9612E5788EA]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /porr/q
   [junit4]   2> 2343817 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2343817 INFO  (Thread-6168) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2343817 INFO  (Thread-6168) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2343917 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.ZkTestServer start zk server on port:61663
   [junit4]   2> 2343917 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2343918 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2343921 INFO  (zkCallback-1756-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c0e3463 name:ZooKeeperConnection Watcher:127.0.0.1:61663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2343921 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2343921 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2343921 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2343924 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2343925 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2343925 INFO  (zkCallback-1757-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@65c94579 name:ZooKeeperConnection Watcher:127.0.0.1:61663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2343926 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2343926 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2343926 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2343928 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2343931 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2343933 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2343936 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2343936 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2343940 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 2343940 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2343942 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2343942 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2343944 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2343945 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2343947 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2343947 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2343949 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2343950 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2343952 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2343952 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2343954 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2343954 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2343957 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2343957 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2343959 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2343959 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2343962 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2343962 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2344083 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores/collection1
   [junit4]   2> 2344086 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 2344088 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d0cb1c9{/porr/q,null,AVAILABLE}
   [junit4]   2> 2344088 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.e.j.s.ServerConnector Started ServerConnector@a0918df{HTTP/1.1}{127.0.0.1:43956}
   [junit4]   2> 2344088 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.e.j.s.Server Started @2348981ms
   [junit4]   2> 2344088 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/tempDir-001/control/data, hostContext=/porr/q, hostPort=43956, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores}
   [junit4]   2> 2344088 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@659e0bfd
   [junit4]   2> 2344088 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/'
   [junit4]   2> 2344088 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2344089 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2344110 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2344111 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2344113 INFO  (zkCallback-1758-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67e3c6ff name:ZooKeeperConnection Watcher:127.0.0.1:61663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2344113 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2344113 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2344115 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2344115 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/solr.xml
   [junit4]   2> 2344118 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores
   [junit4]   2> 2344119 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer New CoreContainer 1056100039
   [junit4]   2> 2344119 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/]
   [junit4]   2> 2344119 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.CoreContainer loading shared library: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/lib
   [junit4]   2> 2344119 WARN  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/lib).
   [junit4]   2> 2344125 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 2344126 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2344127 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2344127 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2344127 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61663/solr
   [junit4]   2> 2344127 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2344127 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2344127 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2344129 INFO  (zkCallback-1760-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3667a9a1 name:ZooKeeperConnection Watcher:127.0.0.1:61663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2344129 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2344129 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2344131 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2344132 INFO  (zkCallback-1761-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2ebfa0db name:ZooKeeperConnection Watcher:127.0.0.1:61663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2344132 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2344133 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2344137 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 2344141 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 2344144 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 2344147 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 2344150 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2344152 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2344154 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2344156 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2344157 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43956_porr%2Fq
   [junit4]   2> 2344158 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:43956_porr%2Fq
   [junit4]   2> 2344160 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2344162 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 2344164 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2344165 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000
   [junit4]   2> 2344165 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43956_porr%2Fq
   [junit4]   2> 2344165 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2344167 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.Overseer Overseer (id=94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000) starting
   [junit4]   2> 2344169 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2344178 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2344178 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2344178 INFO  (OverseerCollectionConfigSetProcessor-94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 2344179 INFO  (OverseerStateUpdate-94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2344194 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 2344194 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2344194 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.CoresLocator Looking for core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores
   [junit4]   2> 2344195 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2344195 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.CoresLocator Found core collection1 in /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores/collection1/
   [junit4]   2> 2344195 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2344196 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.s.SolrDispatchFilter user.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0
   [junit4]   2> 2344196 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2344197 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 2344197 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2344199 INFO  (OverseerStateUpdate-94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:43956/porr/q",
   [junit4]   2>   "node_name":"127.0.0.1:43956_porr%2Fq",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 2344200 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 2344200 INFO  (OverseerStateUpdate-94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:43956/porr/q",
   [junit4]   2>   "node_name":"127.0.0.1:43956_porr%2Fq",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2344201 INFO  (OverseerStateUpdate-94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 2344201 INFO  (OverseerStateUpdate-94694469826052100-127.0.0.1:43956_porr%2Fq-n_0000000000) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2344203 INFO  (zkCallback-1761-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 2345201 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2345201 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 2345202 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 2345202 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 2345203 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 2345203 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001/control-001/cores/collection1/'
   [junit4]   2> 2345203 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2345203 INFO  (coreLoadExecutor-7347-thread-1-processing-n:127.0.0.1:43956_porr%2Fq) [n:127.0.0.1:43956_porr%2Fq c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted t

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

il.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2388757 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@52d17c81{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 2388757 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1aa0ec70{/porr/q,null,UNAVAILABLE}
   [junit4]   2> 2388761 INFO  (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[3BC2B9612E5788EA]) [n:127.0.0.1:42844_porr%2Fq c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ZkTestServer connecting to 127.0.0.1:61663 61663
   [junit4]   2> 2388774 INFO  (Thread-6168) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:61663 61663
   [junit4]   2> 2388777 WARN  (Thread-6168) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	9	/solr/aliases.json
   [junit4]   2> 	9	/solr/configs/gettingstarted/managed-schema
   [junit4]   2> 	8	/solr/configs/gettingstarted
   [junit4]   2> 	5	/solr/security.json
   [junit4]   2> 	5	/solr/configs/conf1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	9	/solr/clusterstate.json
   [junit4]   2> 	4	/solr/collections/gettingstarted/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	9	/solr/live_nodes
   [junit4]   2> 	9	/solr/collections
   [junit4]   2> 	5	/solr/overseer/collection-queue-work
   [junit4]   2> 	4	/solr/overseer/queue
   [junit4]   2> 	3	/solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrCloudExampleTest -Dtests.method=testLoadDocsIntoGettingStartedCollection -Dtests.seed=3BC2B9612E5788EA -Dtests.slow=true -Dtests.locale=es_CO -Dtests.timezone=Asia/Magadan -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 45.0s J0 | SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Delete action failed!
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3BC2B9612E5788EA:28A18B0E1F38314C]:0)
   [junit4]    > 	at org.apache.solr.cloud.SolrCloudExampleTest.doTestDeleteAction(SolrCloudExampleTest.java:169)
   [junit4]    > 	at org.apache.solr.cloud.SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection(SolrCloudExampleTest.java:145)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2388798 INFO  (SUITE-SolrCloudExampleTest-seed#[3BC2B9612E5788EA]-worker) [n:127.0.0.1:42844_porr%2Fq c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.SolrCloudExampleTest_3BC2B9612E5788EA-001
   [junit4]   2> Oct 15, 2015 3:29:45 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=es_CO, timezone=Asia/Magadan
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_60 (64-bit)/cpus=3,threads=1,free=291577248,total=513802240
   [junit4]   2> NOTE: All tests run in this JVM: [TestClusterStateMutator, DistributedQueryElevationComponentTest, TestClassNameShortening, TestSort, TestIBSimilarityFactory, DistributedFacetPivotSmallAdvancedTest, FileUtilsTest, TestSolrDeletionPolicy1, TestInitParams, TestReplicationHandler, SolrRequestParserTest, TestMacros, TestDistribDocBasedVersion, AddSchemaFieldsUpdateProcessorFactoryTest, RecoveryAfterSoftCommitTest, TestSweetSpotSimilarityFactory, DateRangeFieldTest, TestCollationFieldDocValues, TestStressVersions, TestFieldSortValues, TestDistributedStatsComponentCardinality, TestShortCircuitedRequests, RequestLoggingTest, UUIDUpdateProcessorFallbackTest, SaslZkACLProviderTest, PluginInfoTest, TestQuerySenderNoQuery, DistribJoinFromCollectionTest, HdfsUnloadDistributedZkTest, XmlUpdateRequestHandlerTest, PKIAuthenticationIntegrationTest, TestRTGBase, CustomCollectionTest, DistribCursorPagingTest, JSONWriterTest, TestDocSet, TermsComponentTest, TestFunctionQuery, SpatialFilterTest, TestUniqueKeyFieldResource, DirectUpdateHandlerTest, CleanupOldIndexTest, TestLRUCache, TestElisionMultitermQuery, VersionInfoTest, XsltUpdateRequestHandlerTest, TestExactStatsCache, TestJmxMonitoredMap, OverseerCollectionConfigSetProcessorTest, OverriddenZkACLAndCredentialsProvidersTest, TestComplexPhraseQParserPlugin, SimplePostToolTest, CurrencyFieldXmlFileTest, SolrCoreTest, IndexBasedSpellCheckerTest, TestAnalyzedSuggestions, FullHLLTest, HdfsNNFailoverTest, HdfsLockFactoryTest, TestOrdValues, TestEmbeddedSolrServerConstructors, BadComponentTest, PolyFieldTest, TestSearcherReuse, UnloadDistributedZkTest, ChangedSchemaMergeTest, OverseerTest, FacetPivotSmallTest, TestLMDirichletSimilarityFactory, TestTolerantSearch, TestWordDelimiterFilterFactory, LeaderElectionTest, TestSolrCloudWithKerberosAlt, HdfsRecoverLeaseTest, TestManagedStopFilterFactory, TestDynamicFieldResource, StandardRequestHandlerTest, SpatialRPTFieldTypeTest, RequiredFieldsTest, TestNoOpRegenerator, ZkStateReaderTest, TestSolrQueryParserDefaultOperatorResource, FastVectorHighlighterTest, TestExpandComponent, ParsingFieldUpdateProcessorsTest, ResourceLoaderTest, TestSystemIdResolver, MBeansHandlerTest, ShardRoutingTest, MoreLikeThisHandlerTest, DistributedSpellCheckComponentTest, DistributedFacetPivotWhiteBoxTest, CollectionsAPIAsyncDistributedZkTest, UpdateParamsTest, ExitableDirectoryReaderTest, HttpPartitionTest, QueryElevationComponentTest, TestStandardQParsers, TestSolrQueryParserResource, ScriptEngineTest, StatelessScriptUpdateProcessorFactoryTest, AnalyticsQueryTest, TestBlendedInfixSuggestions, TestComponentsName, TestReRankQParserPlugin, ZkStateWriterTest, TestConfigSets, SoftAutoCommitTest, DistributedExpandComponentTest, TestLFUCache, TestNonDefinedSimilarityFactory, TestSolrCLIRunExample, QueryResultKeyTest, TestDefaultStatsCache, SimpleMLTQParserTest, TestXIncludeConfig, TestPhraseSuggestions, PrimitiveFieldTypeTest, TestConfigSetsAPI, HighlighterTest, DateFieldTest, RecoveryZkTest, TestLRUStatsCache, CoreMergeIndexesAdminHandlerTest, TestManagedSchema, TestCrossCoreJoin, TestConfig, MergeStrategyTest, SystemInfoHandlerTest, TestRestManager, JavabinLoaderTest, TestExactSharedStatsCache, TestAnalyzeInfixSuggestions, TestSurroundQueryParser, BasicDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, TestDistributedGrouping, TestFaceting, TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestCoreContainer, SpellCheckComponentTest, ConvertedLegacyTest, TestLazyCores, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, SpellCheckCollatorTest, DocValuesTest, TestStressLucene, TestCSVLoader, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, SortByFunctionTest, DocValuesMultiTest, DistanceFunctionTest, CacheHeaderTest, TestWriterPerf, FileBasedSpellCheckerTest, TestValueSourceCache, TestIndexingPerformance, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, SolrPluginUtilsTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, JsonLoaderTest, CSVRequestHandlerTest, TestBinaryResponseWriter, SOLR749Test, TestQuerySenderListener, AlternateDirectoryTest, TestSolrIndexConfig, ResponseLogComponentTest, SolrIndexConfigTest, TestMergePolicyConfig, MultiTermTest, SampleTest, TestBinaryField, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, OutputWriterTest, ExternalFileFieldSortTest, DirectSolrConnectionTest, TestCodecSupport, EchoParamsTest, TestPerFieldSimilarity, TestBM25SimilarityFactory, TestFastWriter, TestFastOutputStream, URLClassifyProcessorTest, DateMathParserTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, CircularListTest, DistributedMLTComponentTest, CursorPagingTest, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestJettySolrRunner, ActionThrottleTest, AliasIntegrationTest, AsyncMigrateRouteKeyTest, CollectionTooManyReplicasTest, DeleteInactiveReplicaTest, DeleteShardTest, DistributedQueueTest, LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, SSLMigrationTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, SolrCloudExampleTest]
   [junit4] Completed [437/552] on J0 in 45.76s, 1 test, 1 failure <<< FAILURES!

[...truncated 402 lines...]
ERROR: Publisher 'Archive the artifacts' failed: no workspace for Lucene-Solr-trunk-Solaris #125
ERROR: Publisher 'Scan for compiler warnings' failed: no workspace for Lucene-Solr-trunk-Solaris #125
ERROR: Publisher 'Publish JUnit test result report' failed: no workspace for Lucene-Solr-trunk-Solaris #125
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



[JENKINS] Lucene-Solr-trunk-Solaris (64bit/jdk1.8.0) - Build # 126 - Still Failing!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Solaris/126/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC

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

Error Message:
List size mismatch @ spellcheck/suggestions

Stack Trace:
java.lang.RuntimeException: List size mismatch @ spellcheck/suggestions
	at __randomizedtesting.SeedInfo.seed([74CFDE8265734115:FC9BE158CB8F2CED]:0)
	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:854)
	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:801)
	at org.apache.solr.handler.component.SpellCheckComponentTest.test(SpellCheckComponentTest.java:96)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:873)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10049 lines...]
   [junit4] Suite: org.apache.solr.handler.component.SpellCheckComponentTest
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001
   [junit4]   2> 1874151 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1874152 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1874152 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1874152 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1874152 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr
   [junit4]   2> 1874153 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1874153 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1874175 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1874178 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1874185 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
   [junit4]   2> 1874185 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.IndexSchema Reading Solr Schema from /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 1874189 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1874301 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1874305 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1874306 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1874312 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1874314 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1874316 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1874316 WARN  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1874317 WARN  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1874317 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1874317 WARN  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1874317 WARN  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1874317 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1874318 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr
   [junit4]   2> 1874318 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/'
   [junit4]   2> 1874318 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1874318 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr
   [junit4]   2> 1874318 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.CoreContainer New CoreContainer 1010931466
   [junit4]   2> 1874318 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/]
   [junit4]   2> 1874318 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.CoreContainer loading shared library: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/lib
   [junit4]   2> 1874318 WARN  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/lib).
   [junit4]   2> 1874335 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1874338 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1874339 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1874339 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1874339 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1874339 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1874340 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig-spellcheckcomponent.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=collection1, collection=collection1, absoluteInstDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/, dataDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001, shard=shard1}
   [junit4]   2> 1874340 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1874341 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1874341 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr
   [junit4]   2> 1874342 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1874342 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1874365 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1874369 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1874377 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
   [junit4]   2> 1874377 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.IndexSchema Reading Solr Schema from /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 1874385 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1874504 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1874507 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1874509 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1874515 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1874517 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1874518 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1874519 WARN  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1874519 WARN  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1874519 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1874519 WARN  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1874519 WARN  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1874520 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 1874520 INFO  (coreLoadExecutor-5416-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 1874520 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1874520 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/], dataDir=[null]
   [junit4]   2> 1874520 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
   [junit4]   2> 1874521 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001
   [junit4]   2> 1874521 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001/index/
   [junit4]   2> 1874521 WARN  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001/index' doesn't exist. Creating new index...
   [junit4]   2> 1874521 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001/index
   [junit4]   2> 1874521 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19081313858865637]
   [junit4]   2> 1874523 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_1,generation=1}
   [junit4]   2> 1874523 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1874523 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001/
   [junit4]   2> 1874526 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1874528 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1874529 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1874530 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1874530 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1874532 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,spellCheckCompRH1,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1874533 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1874533 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1874533 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1874533 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14249079993480296]
   [junit4]   2> 1874535 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_1,generation=1}
   [junit4]   2> 1874535 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1874535 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@20dceb09[collection1] main
   [junit4]   2> 1874535 WARN  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 1874536 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1874536 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1874536 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1874536 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1874552 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
   [junit4]   2> 1874572 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
   [junit4]   2> 1874572 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
   [junit4]   2> 1874572 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct_lowerfilt,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=lowerfilt}
   [junit4]   2> 1874596 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
   [junit4]   2> 1874596 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=threshold_direct,classname=solr.DirectSolrSpellChecker,field=lowerfilt,spellcheckIndexDir=spellcheckerThreshold,buildOnCommit=true,thresholdTokenFrequency=0.29}
   [junit4]   2> 1874596 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
   [junit4]   2> 1874608 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
   [junit4]   2> 1874664 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
   [junit4]   2> 1874688 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
   [junit4]   2> 1874689 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1874689 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1874689 INFO  (coreLoadExecutor-5416-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1874689 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
   [junit4]   2> 1874690 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1874690 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
   [junit4]   2> 1874690 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1874690 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
   [junit4]   2> 1874691 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1874692 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
   [junit4]   2> 1874692 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1874692 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1874694 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1874695 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1874695 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1874698 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1874699 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1874701 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1874701 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1874702 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1874702 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1874702 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1874703 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1874703 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 1874704 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1874704 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 1874704 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1874711 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_2,generation=2}
   [junit4]   2> 1874712 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1874715 WARN  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
   [junit4]   2> 1874716 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@5343cd8c[collection1] main
   [junit4]   2> 1874716 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1874716 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1874717 INFO  (searcherExecutor-5417-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@20dceb09[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1874717 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1874717 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
   [junit4]   2> 1874721 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1874721 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
   [junit4]   2> 1874721 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1874721 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
   [junit4]   2> 1874722 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
   [junit4]   2> 1874722 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1874723 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1874723 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1874724 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1874724 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1874725 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1874725 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5343cd8c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C10)))}
   [junit4]   2> 1874725 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 20
   [junit4]   2> 1874726 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemt&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=0 
   [junit4]   2> 1874727 ERROR (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=List size mismatch @ spellcheck/suggestions
   [junit4]   2>  expected =/spellcheck=={'suggestions':['documemt',{'numFound':1,'startOffset':0,'endOffset':8,'suggestion':['document']}]}
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":0},
   [junit4]   2>   "response":{"numFound":0,"start":0,"docs":[]
   [junit4]   2>   },
   [junit4]   2>   "spellcheck":{
   [junit4]   2>     "suggestions":[]}}
   [junit4]   2> 
   [junit4]   2>  request = q=documemt&qt=spellCheckCompRH&spellcheck=true&wt=xml
   [junit4]   2> 1874727 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 1874727 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1874727 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1874727 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1874727 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_2,generation=2}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_3,generation=3}
   [junit4]   2> 1874728 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 1874728 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@3d9712bc[collection1] main
   [junit4]   2> 1874728 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1874728 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1874741 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1874753 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1874765 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1874765 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1874789 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1874817 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1874860 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d9712bc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1874860 INFO  (TEST-SpellCheckComponentTest.test-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 132
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SpellCheckComponentTest -Dtests.method=test -Dtests.seed=74CFDE8265734115 -Dtests.slow=true -Dtests.locale=es_US -Dtests.timezone=Africa/Bamako -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.18s J0 | SpellCheckComponentTest.test <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: List size mismatch @ spellcheck/suggestions
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([74CFDE8265734115:FC9BE158CB8F2CED]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:854)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:801)
   [junit4]    > 	at org.apache.solr.handler.component.SpellCheckComponentTest.test(SpellCheckComponentTest.java:96)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1874874 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testThresholdTokenFrequency
   [junit4]   2> 1874875 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1874875 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1874876 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1874876 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1874877 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1874877 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1874878 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1874878 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 1874878 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1874879 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 1874879 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1874882 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_3,generation=3}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_4,generation=4}
   [junit4]   2> 1874882 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 4
   [junit4]   2> 1874883 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@2bfe5681[collection1] main
   [junit4]   2> 1874884 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1874884 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1874983 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1874998 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1875043 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1875043 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1875118 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1875239 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1875385 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2bfe5681[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C10)))}
   [junit4]   2> 1875385 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 506
   [junit4]   2> 1875387 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documenq&qt=spellCheckCompRH&spellcheck.dictionary=threshold&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=1 
   [junit4]   2> 1875390 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documenq&qt=spellCheckCompRH&spellcheck.dictionary=threshold_direct&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=3 
   [junit4]   2> 1875409 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testThresholdTokenFrequency
   [junit4]   2> 1875409 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1875409 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1875409 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1875410 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_4,generation=4}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_5,generation=5}
   [junit4]   2> 1875410 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 5
   [junit4]   2> 1875410 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@31e09daf[collection1] main
   [junit4]   2> 1875410 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1875411 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1875473 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1875522 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1875574 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1875574 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1875621 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1875662 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1875716 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@31e09daf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1875716 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 307
   [junit4]   2> 1875731 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollate
   [junit4]   2> 1875731 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1875732 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1875732 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1875732 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1875732 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1875733 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1875733 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1875733 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 1875733 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1875734 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 1875734 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1875737 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_5,generation=5}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_6,generation=6}
   [junit4]   2> 1875737 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 6
   [junit4]   2> 1875738 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@6236be7b[collection1] main
   [junit4]   2> 1875738 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1875738 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1875881 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1875925 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1876144 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1876144 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1876286 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1876416 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1876552 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6236be7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.0.0):C10)))}
   [junit4]   2> 1876552 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 819
   [junit4]   2> 1876692 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemt&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.build=true&spellcheck.collate=true} hits=0 status=0 QTime=138 
   [junit4]   2> 1876694 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemt+lowerfilt:broen^4&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=1 
   [junit4]   2> 1876696 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemtsss+broens&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=1 
   [junit4]   2> 1876697 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=pixma&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=0 
   [junit4]   2> 1876698 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollate
   [junit4]   2> 1876698 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1876698 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1876698 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1876699 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_6,generation=6}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_7,generation=7}
   [junit4]   2> 1876699 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 7
   [junit4]   2> 1876699 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@7d99204f[collection1] main
   [junit4]   2> 1876699 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1876699 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1876741 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1876779 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1876820 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1876820 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1876872 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1876923 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1876971 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7d99204f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1876971 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 273
   [junit4]   2> 1876980 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedResultsCount
   [junit4]   2> 1876980 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1876981 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1876981 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1876981 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1876981 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1876982 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1876982 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1876982 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 1876982 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1876983 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 1876983 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1876985 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_7,generation=7}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_8,generation=8}
   [junit4]   2> 1876985 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 8
   [junit4]   2> 1876987 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@2ab5c903[collection1] main
   [junit4]   2> 1876987 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1876987 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1877129 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1877170 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1877282 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1877282 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1877400 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1877530 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1877668 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ab5c903[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C10)))}
   [junit4]   2> 1877668 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 685
   [junit4]   2> 1877812 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bluo&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false} hits=0 status=0 QTime=143 
   [junit4]   2> 1877812 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bluo&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=3&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=0 
   [junit4]   2> 1877813 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedResultsCount
   [junit4]   2> 1877813 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1877813 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1877813 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1877813 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_8,generation=8}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_9,generation=9}
   [junit4]   2> 1877814 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 9
   [junit4]   2> 1877814 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@672eb871[collection1] main
   [junit4]   2> 1877814 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1877814 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1877878 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1877918 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1877955 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1877955 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1877992 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1878030 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1878061 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@672eb871[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1878061 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 248
   [junit4]   2> 1878068 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testReloadOnStart
   [junit4]   2> 1878069 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 1
   [junit4]   2> 1878070 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1878070 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1878071 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1878071 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1878071 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1878072 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1878072 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 1878072 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1878073 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 1878073 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1878076 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_9,generation=9}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_a,generation=10}
   [junit4]   2> 1878076 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 10
   [junit4]   2> 1878078 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@5f8cc3d9[collection1] main
   [junit4]   2> 1878078 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1878079 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1878221 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1878260 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1878405 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1878405 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1878529 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1878647 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1878771 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f8cc3d9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):C10)))}
   [junit4]   2> 1878771 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 698
   [junit4]   2> 1878772 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1878773 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1878776 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_a,generation=10}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_b,generation=11}
   [junit4]   2> 1878777 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 11
   [junit4]   2> 1878777 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@11b1fc97[collection1] main
   [junit4]   2> 1878777 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1878778 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1878906 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1878940 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1879073 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1879074 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1879212 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1879335 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1879489 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11b1fc97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):C10/1:delGen=1) Uninverting(_5(6.0.0):C1)))}
   [junit4]   2> 1879489 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 716
   [junit4]   2> 1879644 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&spellcheck.q=ttle&qt=spellCheckCompRH&spellcheck.dictionary=default&spellcheck=true&wt=xml&spellcheck.build=true} hits=10 status=0 QTime=154 
   [junit4]   2> 1879645 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1879649 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testReloadOnStart
   [junit4]   2> 1879649 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1879649 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1879649 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1879649 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_b,generation=11}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_c,generation=12}
   [junit4]   2> 1879650 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 12
   [junit4]   2> 1879650 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@330421bd[collection1] main
   [junit4]   2> 1879650 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1879650 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1879693 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1879732 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1879777 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1879777 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1879804 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1879847 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1879872 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@330421bd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1879872 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 222
   [junit4]   2> 1879875 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testPerDictionary
   [junit4]   2> 1879875 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1879876 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1879876 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1879876 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1879877 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1879877 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1879877 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1879877 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 1879877 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1879878 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 1879878 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1879880 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_c,generation=12}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_d,generation=13}
   [junit4]   2> 1879880 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 13
   [junit4]   2> 1879881 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@38f429b0[collection1] main
   [junit4]   2> 1879882 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1879882 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1879964 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1879975 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1880036 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1880036 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1880098 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1880176 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1880230 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@38f429b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(6.0.0):C10)))}
   [junit4]   2> 1880230 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 352
   [junit4]   2> 1880231 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemt&qt=spellCheckCompRH&spellcheck.dictionary=perDict&json.nl=map&spellcheck..perDict.foo=bar&spellcheck=true&spellcheck..perDict.bar=foo&wt=xml&spellcheck.build=true} hits=0 status=0 QTime=0 
   [junit4]   2> 1880231 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testPerDictionary
   [junit4]   2> 1880231 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1880231 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1880231 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1880232 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_d,generation=13}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@6f48ce3f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3261352e),segFN=segments_e,generation=14}
   [junit4]   2> 1880232 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.c.SolrCore newest commit generation = 14
   [junit4]   2> 1880232 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@6a902558[collection1] main
   [junit4]   2> 1880232 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1880232 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1880244 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1880267 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1880297 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1880297 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1880322 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1880338 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1880353 INFO  (searcherExecutor-5417-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a902558[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1880353 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 122
   [junit4]   2> 1880357 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRebuildOnCommit
   [junit4]   2> 1880358 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1880358 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1880359 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1880359 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1880359 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1880359 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1880360 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1880360 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 1880361 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1880362 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 1880362 INFO  (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[74CFDE8265734115]) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false

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

.UpdateHandler closing DirectUpdateHandler2{commits=20,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=92,cumulative_deletesById=0,cumulative_deletesByQuery=9,cumulative_errors=0}
   [junit4]   2> 1882398 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 1882398 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1882399 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1882399 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1882399 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1882399 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001/index [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 1882399 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001/index
   [junit4]   2> 1882399 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001 [CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001;done=false>>]
   [junit4]   2> 1882399 INFO  (SUITE-SpellCheckComponentTest-seed#[74CFDE8265734115]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001/init-core-data-001
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.handler.component.SpellCheckComponentTest_74CFDE8265734115-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {start1=PostingsFormat(name=LuceneFixedGap), range_facet_l_dv=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=Memory doPackFST= true), intDefault=PostingsFormat(name=Memory doPackFST= false), range_facet_l=PostingsFormat(name=Memory doPackFST= false), lowerfilt1and2=PostingsFormat(name=Memory doPackFST= true), end4=PostingsFormat(name=LuceneFixedGap), end3=PostingsFormat(name=Memory doPackFST= true), end2=Lucene50(blocksize=128), end1=PostingsFormat(name=Memory doPackFST= false), lowerfilt=PostingsFormat(name=Memory doPackFST= true), gram1=PostingsFormat(name=Memory doPackFST= false), id=Lucene50(blocksize=128), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), gram2=Lucene50(blocksize=128), gram3=PostingsFormat(name=Memory doPackFST= true), start3=Lucene50(blocksize=128), gram4=PostingsFormat(name=LuceneFixedGap), start2=PostingsFormat(name=Memory doPackFST= true), word=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false), start4=PostingsFormat(name=Memory doPackFST= false)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, sim=ClassicSimilarity, locale=es_US, timezone=Africa/Bamako
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_60 (64-bit)/cpus=3,threads=1,free=215228248,total=513802240
   [junit4]   2> NOTE: All tests run in this JVM: [BadCopyFieldTest, TestMergePolicyConfig, SOLR749Test, AliasIntegrationTest, RecoveryAfterSoftCommitTest, TestDocSet, StatelessScriptUpdateProcessorFactoryTest, HdfsChaosMonkeySafeLeaderTest, TestAuthorizationFramework, TestSolrDeletionPolicy1, DeleteInactiveReplicaTest, TestCoreContainer, TestReplicaProperties, TestMiniSolrCloudClusterSSL, RegexBoostProcessorTest, TestPseudoReturnFields, MergeStrategyTest, AddBlockUpdateTest, XsltUpdateRequestHandlerTest, TestExtendedDismaxParser, TestPostingsSolrHighlighter, FacetPivotSmallTest, FastVectorHighlighterTest, TestUpdate, HighlighterMaxOffsetTest, TestFilteredDocIdSet, SSLMigrationTest, HdfsRecoverLeaseTest, TestComponentsName, SystemInfoHandlerTest, TestShortCircuitedRequests, DocValuesMultiTest, TestComplexPhraseQParserPlugin, ReplicationFactorTest, TestCollationFieldDocValues, SimplePostToolTest, AsyncMigrateRouteKeyTest, TestSchemaManager, TestFileDictionaryLookup, SolrIndexConfigTest, TestNRTOpen, DateRangeFieldTest, CleanupOldIndexTest, DeleteShardTest, ZkNodePropsTest, SolrRequestParserTest, RequiredFieldsTest, TestJmxIntegration, ScriptEngineTest, TestManagedSchemaFieldTypeResource, TestEmbeddedSolrServerConstructors, DocumentBuilderTest, TriLevelCompositeIdRoutingTest, DistributedSpellCheckComponentTest, SpatialRPTFieldTypeTest, TestSolrConfigHandlerConcurrent, TestReplicationHandler, TestCloudManagedSchemaConcurrent, ChaosMonkeyNothingIsSafeTest, BadComponentTest, DataDrivenBlockJoinTest, CoreMergeIndexesAdminHandlerTest, TestSchemaResource, TestCloudPivotFacet, TestLMDirichletSimilarityFactory, QueryEqualityTest, TestSortByMinMaxFunction, IndexBasedSpellCheckerTest, LeaderFailoverAfterPartitionTest, TestPhraseSuggestions, TestSearcherReuse, CurrencyFieldXmlFileTest, TestHashPartitioner, TestFastLRUCache, TestSmileRequest, AtomicUpdatesTest, TestBlendedInfixSuggestions, TestDefaultStatsCache, TestSha256AuthenticationProvider, TestHighFrequencyDictionaryFactory, TestDistributedStatsComponentCardinality, TestConfig, SpatialFilterTest, TestDynamicFieldCollectionResource, SaslZkACLProviderTest, TestNonDefinedSimilarityFactory, HdfsCollectionsAPIDistributedZkTest, FullHLLTest, DistributedSuggestComponentTest, QueryResultKeyTest, StandardRequestHandlerTest, StressHdfsTest, AddSchemaFieldsUpdateProcessorFactoryTest, SolrCoreTest, RequestLoggingTest, TestIBSimilarityFactory, TestMacros, TestMaxScoreQueryParser, TestManagedStopFilterFactory, CdcrReplicationDistributedZkTest, TestTrackingShardHandlerFactory, UpdateParamsTest, TestLRUCache, HdfsUnloadDistributedZkTest, CustomCollectionTest, TestJsonFacets, HdfsNNFailoverTest, SyncSliceTest, LeaderElectionTest, TestDynamicFieldResource, TestExclusionRuleCollectionAccess, TestHighlightDedupGrouping, TestClassNameShortening, TestLFUCache, CollectionsAPIAsyncDistributedZkTest, DirectUpdateHandlerTest, HdfsLockFactoryTest, TestNoOpRegenerator, TestAnalyzeInfixSuggestions, TestRuleBasedAuthorizationPlugin, TestElisionMultitermQuery, DisMaxRequestHandlerTest, QueryElevationComponentTest, PingRequestHandlerTest, AnalyticsMergeStrategyTest, DistributedExpandComponentTest, TestDFRSimilarityFactory, TestDynamicLoading, TestSolrJ, TestSystemIdResolver, ReturnFieldsTest, TestPartialUpdateDeduplication, TestConfigSetsAPI, PathHierarchyTokenizerFactoryTest, AlternateDirectoryTest, SimpleFacetsTest, TestGroupingSearch, TestOverriddenPrefixQueryForCustomFieldType, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, ClusterStateUpdateTest, TestRandomFaceting, TestZkChroot, TestRandomDVFaceting, ZkCLITest, TestFaceting, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestJoin, TestStressVersions, HardAutoCommitTest, TestRangeQuery, TestSolr4Spatial, SpellCheckComponentTest]
   [junit4] Completed [305/552] on J0 in 8.26s, 9 tests, 1 error <<< FAILURES!

[...truncated 800 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:775: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:719: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:59: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build.xml:233: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/common-build.xml:516: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:1432: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:987: There were test failures: 552 suites (8 ignored), 2170 tests, 1 error, 50 ignored (37 assumptions) [seed: 74CFDE8265734115]

Total time: 87 minutes 15 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