Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11719/
Java: 32bit/jdk1.8.0_31 -client -XX:+UseSerialGC

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.search.TestSolr4Spatial

Error Message:
SolrCore.getOpenCount()==6

Stack Trace:
java.lang.RuntimeException: SolrCore.getOpenCount()==6
        at __randomizedtesting.SeedInfo.seed([CB8557CFFAC71400]:0)
        at org.apache.solr.util.TestHarness.close(TestHarness.java:356)
        at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:683)
        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:226)
        at sun.reflect.GeneratedMethodAccessor46.invoke(Unknown Source)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:483)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        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:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  junit.framework.TestSuite.org.apache.solr.search.TestSolr4Spatial

Error Message:
2 threads leaked from SUITE scope at org.apache.solr.search.TestSolr4Spatial:   
  1) Thread[id=5074, name=searcherExecutor-2496-thread-1, state=WAITING, 
group=TGRP-TestSolr4Spatial]         at sun.misc.Unsafe.park(Native Method)     
    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)        
 at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
        at java.lang.Thread.run(Thread.java:745)    2) Thread[id=5075, 
name=Thread-2314, state=WAITING, group=TGRP-TestSolr4Spatial]         at 
java.lang.Object.wait(Native Method)         at 
java.lang.Object.wait(Object.java:502)         at 
org.apache.solr.core.CloserThread.run(CoreContainer.java:902)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE 
scope at org.apache.solr.search.TestSolr4Spatial: 
   1) Thread[id=5074, name=searcherExecutor-2496-thread-1, state=WAITING, 
group=TGRP-TestSolr4Spatial]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=5075, name=Thread-2314, state=WAITING, 
group=TGRP-TestSolr4Spatial]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:502)
        at org.apache.solr.core.CloserThread.run(CoreContainer.java:902)
        at __randomizedtesting.SeedInfo.seed([CB8557CFFAC71400]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.search.TestSolr4Spatial

Error Message:
There are still zombie threads that couldn't be terminated:    1) 
Thread[id=5074, name=searcherExecutor-2496-thread-1, state=WAITING, 
group=TGRP-TestSolr4Spatial]         at sun.misc.Unsafe.park(Native Method)     
    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)        
 at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
        at java.lang.Thread.run(Thread.java:745)    2) Thread[id=5075, 
name=Thread-2314, state=WAITING, group=TGRP-TestSolr4Spatial]         at 
java.lang.Object.wait(Native Method)         at 
java.lang.Object.wait(Object.java:502)         at 
org.apache.solr.core.CloserThread.run(CoreContainer.java:902)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   1) Thread[id=5074, name=searcherExecutor-2496-thread-1, state=WAITING, 
group=TGRP-TestSolr4Spatial]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=5075, name=Thread-2314, state=WAITING, 
group=TGRP-TestSolr4Spatial]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:502)
        at org.apache.solr.core.CloserThread.run(CoreContainer.java:902)
        at __randomizedtesting.SeedInfo.seed([CB8557CFFAC71400]:0)




Build Log:
[...truncated 9929 lines...]
   [junit4] Suite: org.apache.solr.search.TestSolr4Spatial
   [junit4]   2> Creating dataDir: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial
 CB8557CFFAC71400-001/init-core-data-001
   [junit4]   2> 974141 T5072 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (true)
   [junit4]   2> 974142 T5072 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 974143 T5072 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 974145 T5072 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 974146 T5072 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 974146 T5072 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 974209 T5072 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 974219 T5072 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.1.0
   [junit4]   2> 974233 T5072 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig-basic.xml
   [junit4]   2> 974234 T5072 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-spatial.xml
   [junit4]   2> 974242 T5072 oass.IndexSchema.readSchema [null] Schema 
name=test
   [junit4]   2> 974306 T5072 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 974307 T5072 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 974308 T5072 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 974308 T5072 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
   [junit4]   2> 974348 T5072 oasc.CoreContainer.<init> New CoreContainer 
15222213
   [junit4]   2> 974349 T5072 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
   [junit4]   2> 974349 T5072 oasc.CoreContainer.load loading shared library: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 974350 T5072 oasc.SolrResourceLoader.addToClassLoader WARN 
Can't find (or read) directory to add to classloader: lib (resolved as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib).
   [junit4]   2> 974363 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 600000
   [junit4]   2> 974363 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: null
   [junit4]   2> 974364 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 60000
   [junit4]   2> 974364 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 974365 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 974365 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 974366 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 974366 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 974366 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 974367 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 974367 T5072 oashc.HttpShardHandlerFactory.getParameter 
Setting useRetries to: false
   [junit4]   2> 974368 T5072 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 974369 T5072 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 974369 T5072 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 974369 T5072 oasc.CoreContainer.load Node Name: testNode
   [junit4]   2> 974372 T5073 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 974373 T5073 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 974373 T5073 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 974373 T5073 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 974413 T5073 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 974419 T5073 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.1.0
   [junit4]   2> 974430 T5073 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig-basic.xml
   [junit4]   2> 974431 T5073 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-spatial.xml
   [junit4]   2> 974436 T5073 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 974466 T5073 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 974466 T5073 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 974467 T5073 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 974467 T5073 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/,
 
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial
 CB8557CFFAC71400-001/init-core-data-001/
   [junit4]   2> 974468 T5073 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 974469 T5073 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial
 CB8557CFFAC71400-001/init-core-data-001
   [junit4]   2> 974469 T5073 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial
 CB8557CFFAC71400-001/init-core-data-001/index/
   [junit4]   2> 974469 T5073 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial
 CB8557CFFAC71400-001/init-core-data-001/index' doesn't exist. Creating new 
index...
   [junit4]   2> 974470 T5073 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial
 CB8557CFFAC71400-001/init-core-data-001/index
   [junit4]   2> 974471 T5073 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.AlcoholicMergePolicy: 
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=254036297, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 974472 T5073 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_1,generation=1}
   [junit4]   2> 974473 T5073 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 974475 T5073 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 974475 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 974476 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 974476 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 974477 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 974477 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /config: org.apache.solr.handler.SolrConfigHandler
   [junit4]   2> 974477 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /schema: org.apache.solr.handler.SchemaHandler
   [junit4]   2> 974478 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: org.apache.solr.handler.ReplicationHandler
   [junit4]   2> 974478 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /get: org.apache.solr.handler.RealTimeGetHandler
   [junit4]   2> 974479 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
   [junit4]   2> 974479 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
   [junit4]   2> 974480 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
   [junit4]   2> 974480 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
   [junit4]   2> 974481 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
   [junit4]   2> 974481 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/properties: 
org.apache.solr.handler.admin.PropertiesRequestHandler
   [junit4]   2> 974482 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
   [junit4]   2> 974482 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
   [junit4]   2> 974482 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/ping: org.apache.solr.handler.PingRequestHandler
   [junit4]   2> 974483 T5073 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 974486 T5073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 974488 T5073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 974489 T5073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 974491 T5073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 974492 T5073 oasc.SolrCore.initStatsCache Using default 
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 974493 T5073 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 974493 T5073 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 974494 T5073 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 974494 T5073 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_1,generation=1}
   [junit4]   2> 974495 T5073 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 974495 T5073 oass.SolrIndexSearcher.<init> Opening 
Searcher@11dff5f[collection1] main
   [junit4]   2> 974496 T5073 oasr.ManagedResourceStorage.newStorageIO WARN 
Cannot write to config directory 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 974496 T5073 oasr.RestManager.init Initializing RestManager 
with initArgs: {}
   [junit4]   2> 974496 T5073 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 974497 T5073 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 974497 T5073 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 974498 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@11dff5f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974498 T5073 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 974500 T5072 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 974507 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testSortMultiVal {p0=bbox}
   [junit4]   2> ASYNC  NEW_CORE C2575 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 974508 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974509 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974514 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974515 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_2,generation=2}
   [junit4]   2> 974517 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 2
   [junit4]   2> 974518 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@502b78[collection1] main
   [junit4]   2> 974518 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974518 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@502b78[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974519 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 974521 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testSortMultiVal {p0=bbox}
   [junit4] IGNOR/A 0.04s J1 | TestSolr4Spatial.testSortMultiVal {p0=bbox}
   [junit4]    > Assumption #1: Multivalue not supported for this field
   [junit4]   2> 974550 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testSpatialConfig {p0=bbox}
   [junit4]   2> 974551 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974552 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974553 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974554 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_3,generation=3}
   [junit4]   2> 974554 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 3
   [junit4]   2> 974555 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@ef122[collection1] main
   [junit4]   2> 974556 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974556 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@ef122[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974557 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 974558 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testSpatialConfig {p0=bbox}
   [junit4]   2> 974567 T5072 oas.SolrTestCaseJ4.setUp ###Starting testSort 
{p0=bbox}
   [junit4]   2> 974567 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974568 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974570 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974571 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_4,generation=4}
   [junit4]   2> 974571 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 4
   [junit4]   2> 974572 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@694a50[collection1] main
   [junit4]   2> 974573 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974573 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@694a50[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974573 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 974609 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100]} 0 34
   [junit4]   2> 974611 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[101]} 0 0
   [junit4]   2> 974613 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[999]} 0 0
   [junit4]   2> 974615 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974626 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_5,generation=5}
   [junit4]   2> 974627 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 5
   [junit4]   2> 974632 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@a9e31b[collection1] main
   [junit4]   2> 974633 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974633 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@a9e31b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):C3)))}
   [junit4]   2> 974634 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 19
   [junit4]   2> 974661 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!bbox+sfield%3Dbbox+++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&wt=xml} 
hits=2 status=0 QTime=26 
   [junit4]   2> 974670 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!bbox+sfield%3Dbbox+score%3Ddistance++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&sort=score+asc&wt=xml}
 hits=2 status=0 QTime=7 
   [junit4]   2> 974674 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!bbox+sfield%3Dbbox+score%3DrecipDistance++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&sort=score+desc&wt=xml}
 hits=2 status=0 QTime=3 
   [junit4]   2> 974676 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!bbox+sfield%3Dbbox+score%3Ddistance+filter%3Dfalse+pt%3D3.0,4.0+d%3D1.0E-6}&fl=id,score&sort=score+asc&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 974680 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!bbox+sfield%3Dbbox+score%3Ddistance++pt%3D4.0,0.0+d%3D9.0}&fl=id,score&sort=score+asc&wt=xml}
 hits=2 status=0 QTime=3 
   [junit4]   2> 974684 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=-id:999&fl=id,score&sort=query($sortQuery)+asc&wt=xml&sortQuery={!bbox+sfield%3Dbbox+score%3Ddistance++pt%3D3.0,4.0+d%3D9.0}}
 hits=2 status=0 QTime=3 
   [junit4]   2> 974688 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=-id:999&fl=id,score&sort=query($sortQuery)+asc&wt=xml&sortQuery={!bbox+sfield%3Dbbox+score%3Ddistance++pt%3D4.0,0.0+d%3D9.0}}
 hits=2 status=0 QTime=3 
   [junit4]   2> 974689 T5072 oas.SolrTestCaseJ4.tearDown ###Ending testSort 
{p0=bbox}
   [junit4]   2> 974699 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
checkQueryEmptyIndex {p0=bbox}
   [junit4]   2> 974700 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974700 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974702 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974703 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_5,generation=5}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_6,generation=6}
   [junit4]   2> 974703 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 6
   [junit4]   2> 974704 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@10e18e5[collection1] main
   [junit4]   2> 974705 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974705 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@10e18e5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974706 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 974708 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
checkQueryEmptyIndex {p0=bbox}
   [junit4]   2> 974716 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testBadShapeParse400 {p0=bbox}
   [junit4]   2> 974717 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974718 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974719 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974720 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_6,generation=6}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_7,generation=7}
   [junit4]   2> 974721 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 7
   [junit4]   2> 974722 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@1486d02[collection1] main
   [junit4]   2> 974722 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974722 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1486d02[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974723 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 974727 T5072 C2575 oasc.SolrException.log Ignoring exception 
matching .
   [junit4]   2> 974727 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id,bbox&fq={!field+f%3Dbbox}Intersects(NonexistentShape(89.9,-130+d%3D9))&rows=1000&wt=xml}
 status=400 QTime=3 
   [junit4]   2> 974729 T5072 C2575 oasc.SolrException.log Ignoring exception 
matching .
   [junit4]   2> 974730 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id,bbox&fq={!field+f%3Dbbox}Intersects(NonexistentShape(89.9,-130+d%3D9&rows=1000&wt=xml}
 status=400 QTime=2 
   [junit4]   2> 974732 T5072 C2575 oasc.SolrException.log Ignoring exception 
matching .
   [junit4]   2> 974732 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id,bbox&fq={!field+f%3Dbbox}Intersectssss&rows=1000&wt=xml} 
status=400 QTime=1 
   [junit4]   2> 974733 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {} 0 0
   [junit4]   2> 974735 T5072 C2575 oasc.SolrException.log Ignoring exception 
matching NonexistentShape
   [junit4]   2> 974735 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testBadShapeParse400 {p0=bbox}
   [junit4]   2> 974744 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testRangeSyntax {p0=bbox}
   [junit4]   2> 974745 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974745 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974747 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974748 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_7,generation=7}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_8,generation=8}
   [junit4]   2> 974748 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 8
   [junit4]   2> 974749 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@ce5543[collection1] main
   [junit4]   2> 974750 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974750 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@ce5543[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974750 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 974753 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 1
   [junit4]   2> 974756 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 1
   [junit4]   2> 974757 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 974759 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 974761 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5]} 0 1
   [junit4]   2> 974763 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[6]} 0 1
   [junit4]   2> 974765 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[7]} 0 1
   [junit4]   2> 974766 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 974768 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 974770 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10]} 0 1
   [junit4]   2> 974772 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[11]} 0 1
   [junit4]   2> 974774 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[12]} 0 1
   [junit4]   2> 974776 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[13]} 0 1
   [junit4]   2> 974777 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974794 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_8,generation=8}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_9,generation=9}
   [junit4]   2> 974794 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 9
   [junit4]   2> 974799 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@d9f2b4[collection1] main
   [junit4]   2> 974799 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974800 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@d9f2b4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.1.0):C13)))}
   [junit4]   2> 974800 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 23
   [junit4]   2> 974804 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!+score%3Ddistance+df%3Dbbox}[32,-80+TO+"33+,+-79"]&rows=1000&wt=xml}
 hits=1 status=0 QTime=2 
   [junit4]   2> 974806 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testRangeSyntax {p0=bbox}
   [junit4]   2> 974813 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testIntersectFilter {p0=bbox}
   [junit4]   2> 974814 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974815 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974816 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974816 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_9,generation=9}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_a,generation=10}
   [junit4]   2> 974817 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 10
   [junit4]   2> 974818 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@aeae38[collection1] main
   [junit4]   2> 974818 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974819 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@aeae38[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974820 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 974823 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 2
   [junit4]   2> 974825 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 1
   [junit4]   2> 974827 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 1
   [junit4]   2> 974829 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[4]} 0 1
   [junit4]   2> 974830 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 974832 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[6]} 0 1
   [junit4]   2> 974833 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> 974835 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[8]} 0 1
   [junit4]   2> 974836 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 974838 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10]} 0 1
   [junit4]   2> 974839 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[11]} 0 0
   [junit4]   2> 974841 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[12]} 0 1
   [junit4]   2> 974842 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[13]} 0 0
   [junit4]   2> 974843 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974860 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_a,generation=10}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_b,generation=11}
   [junit4]   2> 974861 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 11
   [junit4]   2> 974865 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@b3e2bb[collection1] main
   [junit4]   2> 974866 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974866 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@b3e2bb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.1.0):C13)))}
   [junit4]   2> 974867 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 24
   [junit4]   2> 974872 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dbbox+score%3Dnone}Intersects(ENVELOPE(-0.034898260626377464,+0.23489826062637748,+0.23489805516424955,+-0.03489805516424954))&rows=1000&wt=xml}
 hits=2 status=0 QTime=3 
   [junit4]   2> 974877 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!bbox+sfield%3Dbbox+pt%3D'0.1,0.1'+d%3D15.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=2 status=0 QTime=3 
   [junit4]   2> 974880 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974880 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974883 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[14]} 0 1
   [junit4]   2> 974885 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[15]} 0 1
   [junit4]   2> 974887 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[16]} 0 1
   [junit4]   2> 974888 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[17]} 0 0
   [junit4]   2> 974890 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974900 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_b,generation=11}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_c,generation=12}
   [junit4]   2> 974901 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 12
   [junit4]   2> 974905 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@3e86e4[collection1] main
   [junit4]   2> 974906 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974906 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3e86e4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.1.0):C4)))}
   [junit4]   2> 974907 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 17
   [junit4]   2> 974910 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dbbox}Intersects(ENVELOPE(-26.979611032849906,+26.979611032849906,+26.979611032849906,+-26.979611032849906))&rows=1000&wt=xml}
 hits=3 status=0 QTime=2 
   [junit4]   2> 974915 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!bbox+sfield%3Dbbox+pt%3D'0,0'+d%3D3000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=3 status=0 QTime=2 
   [junit4]   2> 974920 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dbbox+score%3DrecipDistance}Intersects(ENVELOPE(-98.15383081481679,+-95.42537518518321,+44.50628240453783,+42.52777759546217))&rows=1000&wt=xml}
 hits=1 status=0 QTime=2 
   [junit4]   2> 974924 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!bbox+sfield%3Dbbox+pt%3D'43.517030,-96.789603'+d%3D110.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=1 status=0 QTime=2 
   [junit4]   2> 974925 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testIntersectFilter {p0=bbox}
   [junit4]   2> 974934 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testBadScoreParam {p0=bbox}
   [junit4]   2> 974934 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974935 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974936 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974937 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_c,generation=12}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_d,generation=13}
   [junit4]   2> 974938 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 13
   [junit4]   2> 974939 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@8e19a7[collection1] main
   [junit4]   2> 974939 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974939 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@8e19a7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974940 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 974943 T5072 C2575 oasc.SolrException.log Ignoring exception 
matching .
   [junit4]   2> 974943 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!bbox+sfield%3Dbbox+score%3Dbogus+filter%3Dfalse+pt%3D0.0,0.0+d%3D0.0}&qt=standard&start=0&rows=20&version=2.2}
 status=400 QTime=1 
   [junit4]   2> 974944 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testBadScoreParam {p0=bbox}
   [junit4]   2> 974951 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
checkResultFormat {p0=bbox}
   [junit4]   2> 974952 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974953 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974954 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974955 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_d,generation=13}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_e,generation=14}
   [junit4]   2> 974956 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 14
   [junit4]   2> 974956 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@54aa4e[collection1] main
   [junit4]   2> 974957 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974957 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@54aa4e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974958 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 974961 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[11]} 0 2
   [junit4]   2> 974962 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974969 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_e,generation=14}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_f,generation=15}
   [junit4]   2> 974970 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 15
   [junit4]   2> 974974 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@14cd4d[collection1] main
   [junit4]   2> 974974 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974975 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@14cd4d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(5.1.0):C1)))}
   [junit4]   2> 974975 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 974979 T5072 C2575 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id,bbox&fq={!bbox+sfield%3Dbbox+pt%3D89.9,-130+d%3D9}&rows=1000&wt=xml}
 hits=1 status=0 QTime=2 
   [junit4]   2> 974982 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
checkResultFormat {p0=bbox}
   [junit4]   2> 974990 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testSortMultiVal {p0=stqpt_geohash}
   [junit4]   2> 974991 T5072 C2575 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 974991 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 974993 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 974993 T5072 C2575 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_f,generation=15}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_g,generation=16}
   [junit4]   2> 974994 T5072 C2575 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 16
   [junit4]   2> 974995 T5072 C2575 oass.SolrIndexSearcher.<init> Opening 
Searcher@11c07c8[collection1] main
   [junit4]   2> 974996 T5072 C2575 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 974996 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@11c07c8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974997 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 975009 T5072 C2575 
oass.AbstractSpatialPrefixTreeFieldType.newSpatialStrategy 
stqpt_geohash{class=org.apache.solr.schema.SpatialTermQueryPrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={distanceUnits=degrees,
 class=solr.SpatialTermQueryPrefixTreeFieldType, prefixTree=geohash}} strat: 
TermQueryPrefixTreeStrategy field:stqpt_geohash ctx=SpatialContext{geo=true, 
calculator=Haversine, 
worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)} maxLevels: 11
   [junit4]   2> 975023 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100]} 0 25
   [junit4]   2> 975025 T5072 C2575 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[101]} 0 1
   [junit4]   2> 975026 T5072 C2575 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C2576 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 975041 T5072 C2576 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_g,generation=16}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_h,generation=17}
   [junit4]   2> 975041 T5072 C2576 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 17
   [junit4]   2> 975044 T5072 C2576 oass.SolrIndexSearcher.<init> Opening 
Searcher@53eba3[collection1] main
   [junit4]   2> 975044 T5072 C2576 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975045 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@53eba3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(5.1.0):C2)))}
   [junit4]   2> 975045 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 19
   [junit4]   2> 975111 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!+score%3Ddistance++}stqpt_geohash:"Intersects(BUFFER(POINT(4.0+3.0),9.0))"&fl=id,score&sort=score+asc&wt=xml}
 hits=2 status=0 QTime=64 
   [junit4]   2> 975112 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testSortMultiVal {p0=stqpt_geohash}
   [junit4]   2> 975118 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testSpatialConfig {p0=stqpt_geohash}
   [junit4]   2> 975118 T5072 C2576 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 975119 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 975120 T5072 C2576 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975120 T5072 C2576 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_h,generation=17}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_i,generation=18}
   [junit4]   2> 975121 T5072 C2576 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 18
   [junit4]   2> 975121 T5072 C2576 oass.SolrIndexSearcher.<init> Opening 
Searcher@15f7f63[collection1] main
   [junit4]   2> 975122 T5072 C2576 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975122 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@15f7f63[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975123 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 975124 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testSpatialConfig {p0=stqpt_geohash}
   [junit4]   2> 975129 T5072 oas.SolrTestCaseJ4.setUp ###Starting testSort 
{p0=stqpt_geohash}
   [junit4]   2> 975130 T5072 C2576 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 975130 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 975131 T5072 C2576 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975132 T5072 C2576 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_i,generation=18}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_j,generation=19}
   [junit4]   2> 975133 T5072 C2576 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 19
   [junit4]   2> 975133 T5072 C2576 oass.SolrIndexSearcher.<init> Opening 
Searcher@19e79d5[collection1] main
   [junit4]   2> 975134 T5072 C2576 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975134 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@19e79d5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975134 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 975136 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100]} 0 1
   [junit4]   2> 975137 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[101]} 0 0
   [junit4]   2> 975138 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[999]} 0 0
   [junit4]   2> 975139 T5072 C2576 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975142 T5072 C2576 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_j,generation=19}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_k,generation=20}
   [junit4]   2> 975142 T5072 C2576 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 20
   [junit4]   2> 975144 T5072 C2576 oass.SolrIndexSearcher.<init> Opening 
Searcher@1404d9e[collection1] main
   [junit4]   2> 975144 T5072 C2576 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975144 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1404d9e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)))}
   [junit4]   2> 975145 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 975172 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!geofilt+sfield%3Dstqpt_geohash+++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&wt=xml}
 hits=2 status=0 QTime=26 
   [junit4]   2> 975191 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!geofilt+sfield%3Dstqpt_geohash+score%3Ddistance++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&sort=score+asc&wt=xml}
 hits=2 status=0 QTime=18 
   [junit4]   2> 975210 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!geofilt+sfield%3Dstqpt_geohash+score%3DrecipDistance++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&sort=score+desc&wt=xml}
 hits=2 status=0 QTime=18 
   [junit4]   2> 975212 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!+score%3Ddistance+filter%3Dfalse+}stqpt_geohash:"Intersects(BUFFER(POINT(4.0+3.0),1.0E-6))"&fl=id,score&sort=score+asc&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 975230 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!geofilt+sfield%3Dstqpt_geohash+score%3Ddistance++pt%3D4.0,0.0+d%3D9.0}&fl=id,score&sort=score+asc&wt=xml}
 hits=2 status=0 QTime=17 
   [junit4]   2> 975254 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=-id:999&fl=id,score&sort=query($sortQuery)+asc&wt=xml&sortQuery={!+score%3Ddistance++}stqpt_geohash:"Intersects(BUFFER(POINT(4.0+3.0),9.0))"}
 hits=2 status=0 QTime=23 
   [junit4]   2> 975276 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=-id:999&fl=id,score&sort=query($sortQuery)+asc&wt=xml&sortQuery={!geofilt+sfield%3Dstqpt_geohash+score%3Ddistance++pt%3D4.0,0.0+d%3D9.0}}
 hits=2 status=0 QTime=21 
   [junit4]   2> 975277 T5072 oas.SolrTestCaseJ4.tearDown ###Ending testSort 
{p0=stqpt_geohash}
   [junit4]   2> 975282 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
checkQueryEmptyIndex {p0=stqpt_geohash}
   [junit4]   2> 975283 T5072 C2576 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 975283 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 975284 T5072 C2576 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975285 T5072 C2576 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_k,generation=20}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_l,generation=21}
   [junit4]   2> 975285 T5072 C2576 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 21
   [junit4]   2> 975286 T5072 C2576 oass.SolrIndexSearcher.<init> Opening 
Searcher@481aa1[collection1] main
   [junit4]   2> 975286 T5072 C2576 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975286 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@481aa1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975287 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 975296 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash}Intersects(BUFFER(POINT(0.0+0.0),0.8993203677616635))&rows=1000&wt=xml}
 hits=0 status=0 QTime=8 
   [junit4]   2> 975305 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'0,0'+d%3D100.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=0 status=0 QTime=8 
   [junit4]   2> 975306 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
checkQueryEmptyIndex {p0=stqpt_geohash}
   [junit4]   2> 975311 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testBadShapeParse400 {p0=stqpt_geohash}
   [junit4]   2> 975312 T5072 C2576 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 975312 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 975313 T5072 C2576 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975314 T5072 C2576 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_l,generation=21}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_m,generation=22}
   [junit4]   2> 975314 T5072 C2576 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 22
   [junit4]   2> 975315 T5072 C2576 oass.SolrIndexSearcher.<init> Opening 
Searcher@1bacda3[collection1] main
   [junit4]   2> 975315 T5072 C2576 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975315 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1bacda3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975316 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 975319 T5072 C2576 oasc.SolrException.log Ignoring exception 
matching .
   [junit4]   2> 975320 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id,stqpt_geohash&fq={!field+f%3Dstqpt_geohash}Intersects(NonexistentShape(89.9,-130+d%3D9))&rows=1000&wt=xml}
 status=400 QTime=3 
   [junit4]   2> 975321 T5072 C2576 oasc.SolrException.log Ignoring exception 
matching .
   [junit4]   2> 975321 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id,stqpt_geohash&fq={!field+f%3Dstqpt_geohash}Intersects(NonexistentShape(89.9,-130+d%3D9&rows=1000&wt=xml}
 status=400 QTime=1 
   [junit4]   2> 975322 T5072 C2576 oasc.SolrException.log Ignoring exception 
matching .
   [junit4]   2> 975322 T5072 C2576 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id,stqpt_geohash&fq={!field+f%3Dstqpt_geohash}Intersectssss&rows=1000&wt=xml}
 status=400 QTime=1 
   [junit4]   2> 975323 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {} 0 0
   [junit4]   2> 975324 T5072 C2576 oasc.SolrException.log Ignoring exception 
matching NonexistentShape
   [junit4]   2> 975324 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testBadShapeParse400 {p0=stqpt_geohash}
   [junit4]   2> 975330 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testRangeSyntax {p0=stqpt_geohash}
   [junit4]   2> 975330 T5072 C2576 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 975331 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 975332 T5072 C2576 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975332 T5072 C2576 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_m,generation=22}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_n,generation=23}
   [junit4]   2> 975333 T5072 C2576 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 23
   [junit4]   2> 975333 T5072 C2576 oass.SolrIndexSearcher.<init> Opening 
Searcher@cf7dc7[collection1] main
   [junit4]   2> 975334 T5072 C2576 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975334 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@cf7dc7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975334 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 2
   [junit4]   2> 975336 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 975338 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 975339 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 975341 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[4]} 0 1
   [junit4]   2> 975342 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 975344 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[6]} 0 1
   [junit4]   2> 975346 T5072 C2576 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[7]} 0 0
   [junit4]   2> ASYNC  NEW_CORE C2577 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 975357 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[8]} 0 1
   [junit4]   2> 975359 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[9]} 0 0
   [junit4]   2> 975361 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10]} 0 0
   [junit4]   2> 975363 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[11]} 0 1
   [junit4]   2> 975364 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[12]} 0 0
   [junit4]   2> 975366 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[13]} 0 1
   [junit4]   2> 975367 T5072 C2577 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975374 T5072 C2577 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_n,generation=23}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_o,generation=24}
   [junit4]   2> 975374 T5072 C2577 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 24
   [junit4]   2> 975377 T5072 C2577 oass.SolrIndexSearcher.<init> Opening 
Searcher@c7cc38[collection1] main
   [junit4]   2> 975377 T5072 C2577 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975377 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@c7cc38[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(5.1.0):C13)))}
   [junit4]   2> 975378 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 975387 T5072 C2577 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!+score%3Ddistance+df%3Dstqpt_geohash}[32,-80+TO+"33+,+-79"]&rows=1000&wt=xml}
 hits=1 status=0 QTime=7 
   [junit4]   2> 975389 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testRangeSyntax {p0=stqpt_geohash}
   [junit4]   2> 975397 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testIntersectFilter {p0=stqpt_geohash}
   [junit4]   2> 975398 T5072 C2577 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 975399 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 975400 T5072 C2577 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975401 T5072 C2577 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_o,generation=24}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_p,generation=25}
   [junit4]   2> 975402 T5072 C2577 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 25
   [junit4]   2> 975403 T5072 C2577 oass.SolrIndexSearcher.<init> Opening 
Searcher@72e7b9[collection1] main
   [junit4]   2> 975403 T5072 C2577 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975403 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@72e7b9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975404 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 975408 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 3
   [junit4]   2> 975410 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 975412 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 1
   [junit4]   2> 975413 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 975415 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5]} 0 1
   [junit4]   2> 975416 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 975418 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[7]} 0 1
   [junit4]   2> 975419 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 975421 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[9]} 0 1
   [junit4]   2> 975422 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10]} 0 0
   [junit4]   2> 975424 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[11]} 0 1
   [junit4]   2> 975425 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[12]} 0 0
   [junit4]   2> 975427 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[13]} 0 1
   [junit4]   2> 975428 T5072 C2577 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975433 T5072 C2577 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_p,generation=25}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_q,generation=26}
   [junit4]   2> 975434 T5072 C2577 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 26
   [junit4]   2> 975436 T5072 C2577 oass.SolrIndexSearcher.<init> Opening 
Searcher@6dd6a6[collection1] main
   [junit4]   2> 975437 T5072 C2577 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 975437 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6dd6a6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(5.1.0):C13)))}
   [junit4]   2> 975438 T5072 C2577 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 975444 T5072 C2577 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Dnone}Intersects(BUFFER(POINT(1.0+1.0),1.5738106435829113))&rows=1000&wt=xml}
 hits=3 status=0 QTime=5 
   [junit4]   2> 975452 T5072 C2577 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'1,1'+d%3D175.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=3 status=0 QTime=5 
   [junit4]   2> 975462 T5072 C2577 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash}Intersects(BUFFER(POINT(179.8+0.0),1.798640735523327))&rows=1000&wt=xml}
 hits=2 status=0 QTime=7 
   [junit4]   2> 975469 T5072 C2577 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'0,179.8'+d%3D200.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=2 status=0 QTime=6 
   [junit4]   2> ASYNC  NEW_CORE C2578 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 976222 T5072 C2578 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3DrecipDistance}Intersects(BUFFER(POINT(50.0+89.8),1.798640735523327))&rows=1000&wt=xml}
 hits=2 status=0 QTime=751 
   [junit4]   2> ASYNC  NEW_CORE C2579 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 976997 T5072 C2579 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'89.8,+50'+d%3D200.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=2 status=0 QTime=773 
   [junit4]   2> ASYNC  NEW_CORE C2580 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 977737 T5072 C2580 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3DrecipDistance}Intersects(BUFFER(POINT(50.0+-89.8),1.798640735523327))&rows=1000&wt=xml}
 hits=2 status=0 QTime=738 
   [junit4]   2> ASYNC  NEW_CORE C2581 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 978474 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'-89.8,+50'+d%3D200.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=2 status=0 QTime=736 
   [junit4]   2> 978481 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Dnone}Intersects(BUFFER(POINT(-80.0+33.0),2.6979611032849906))&rows=1000&wt=xml}
 hits=2 status=0 QTime=6 
   [junit4]   2> 978492 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'33.0,-80.0'+d%3D300.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=2 status=0 QTime=10 
   [junit4]   2> 978498 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Dnone}Intersects(BUFFER(POINT(1.0+1.0),44.96601838808318))&rows=1000&wt=xml}
 hits=3 status=0 QTime=5 
   [junit4]   2> 978503 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'1,1'+d%3D5000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=3 status=0 QTime=3 
   [junit4]   2> 978512 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Dnone}Intersects(ENVELOPE(-0.034898260626377464,+0.23489826062637748,+0.23489805516424955,+-0.03489805516424954))&rows=1000&wt=xml}
 hits=2 status=0 QTime=7 
   [junit4]   2> 978520 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!bbox+sfield%3Dstqpt_geohash+pt%3D'0.1,0.1'+d%3D15.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=2 status=0 QTime=6 
   [junit4]   2> 978522 T5072 C2581 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 978523 T5072 C2581 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 978524 T5072 C2581 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[14]} 0 0
   [junit4]   2> 978525 T5072 C2581 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[15]} 0 0
   [junit4]   2> 978526 T5072 C2581 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[16]} 0 0
   [junit4]   2> 978527 T5072 C2581 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[17]} 0 0
   [junit4]   2> 978528 T5072 C2581 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 978531 T5072 C2581 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_q,generation=26}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@50e961 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@147f399),segFN=segments_r,generation=27}
   [junit4]   2> 978532 T5072 C2581 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 27
   [junit4]   2> 978533 T5072 C2581 oass.SolrIndexSearcher.<init> Opening 
Searcher@135070e[collection1] main
   [junit4]   2> 978533 T5072 C2581 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 978533 T5074 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@135070e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(5.1.0):C4)))}
   [junit4]   2> 978534 T5072 C2581 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 978551 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3DrecipDistance}Intersects(BUFFER(POINT(0.0+0.0),8.993203677616636))&rows=1000&wt=xml}
 hits=1 status=0 QTime=16 
   [junit4]   2> 978569 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'0,0'+d%3D1000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=1 status=0 QTime=16 
   [junit4]   2> 978576 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(0.0+0.0),17.986407355233272))&rows=1000&wt=xml}
 hits=2 status=0 QTime=6 
   [junit4]   2> 978583 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'0,0'+d%3D2000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=2 status=0 QTime=5 
   [junit4]   2> 978589 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3DrecipDistance}Intersects(ENVELOPE(-26.979611032849906,+26.979611032849906,+26.979611032849906,+-26.979611032849906))&rows=1000&wt=xml}
 hits=3 status=0 QTime=4 
   [junit4]   2> 978595 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!bbox+sfield%3Dstqpt_geohash+pt%3D'0,0'+d%3D3000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=3 status=0 QTime=4 
   [junit4]   2> 978605 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(0.0+0.0),26.98860423652752))&rows=1000&wt=xml}
 hits=3 status=0 QTime=9 
   [junit4]   2> 978616 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'0,0'+d%3D3001.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=3 status=0 QTime=9 
   [junit4]   2> 978626 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Dnone}Intersects(BUFFER(POINT(0.0+0.0),26.980510353217664))&rows=1000&wt=xml}
 hits=3 status=0 QTime=9 
   [junit4]   2> 978636 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'0,0'+d%3D3000.1+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=3 status=0 QTime=9 
   [junit4]   2> 978650 T5072 C2581 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Dnone}Intersects(BUFFER(POINT(-96.789603+43.51703),0.9802592008602133))&rows=1000&wt=xml}
 hits=0 status=0 QTime=12 
   [junit4]   2> ASYNC  NEW_CORE C2582 name=collection1 
org.apache.solr.core.SolrCore@19e38cc
   [junit4]   2> 978672 T5072 C2582 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'43.517030,-96.789603'+d%3D109.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=0 status=0 QTime=21 
   [junit4]   2> 978686 T5072 C2582 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(-96.789603+43.51703),0.9892524045378299))&rows=1000&wt=xml}
 hits=0 status=0 QTime=12 
   [junit4]   2> 978704 T5072 C2582 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!geofilt+sfield%3Dstqpt_geohash+pt%3D'43.517030,-96.789603'+d%3D110.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=0 status=0 QTime=17 
   [junit4]   2> 978712 T5072 C2582 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(ENVELOPE(-98.15383081481679,+-95.42537518518321,+44.50628240453783,+42.52777759546217))&rows=1000&wt=xml}
 hits=1 status=0 QTime=7 
   [junit4]   2> 978720 T5072 C2582 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=*:*&fl=id&fq={!bbox+sfield%3Dstqpt_geohash+pt%3D'43.517030,-96.789603'+d%3D110.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml}
 hits=1 status=0 QTime=7 
   [junit4]   2> 978721 T5072 oas.SolrTestCaseJ4.tearDown ###Ending 
testIntersectFilter {p0=stqpt_geohash}
   [junit4]   2> 978727 T5072 oas.SolrTestCaseJ4.setUp ###Starting 
testBadScoreParam {p0=stqpt_geohash}
   [junit4]   2> 978727 T5072 C2582 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 978728 T5072 C2582 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 978729 T5072 C2582 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 978729 T5072 C2582 oasc.SolrDeletionPolicy.onCommit SolrD

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

Spatial]
   [junit4]   2> 29229 T5071 ccr.ThreadLeakControl.tryToInterruptAll SEVERE 
There are still zombie threads that couldn't be terminated:
   [junit4]   2>           1) Thread[id=5074, 
name=searcherExecutor-2496-thread-1, state=WAITING, group=TGRP-TestSolr4Spatial]
   [junit4]   2>                at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>                at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]   2>                at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]   2>                at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2>                at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
   [junit4]   2>                at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]   2>                at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>                at java.lang.Thread.run(Thread.java:745)
   [junit4]   2>           2) Thread[id=5075, name=Thread-2314, state=WAITING, 
group=TGRP-TestSolr4Spatial]
   [junit4]   2>                at java.lang.Object.wait(Native Method)
   [junit4]   2>                at java.lang.Object.wait(Object.java:502)
   [junit4]   2>                at 
org.apache.solr.core.CloserThread.run(CoreContainer.java:902)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{bbox__xdl=BlockTreeOrds(blocksize=128), 
srpt_geohash=BlockTreeOrds(blocksize=128), 
bbox__maxX=BlockTreeOrds(blocksize=128), bbox__maxY=Lucene50(blocksize=128), 
bbox__minX=FSTOrd50, bbox__minY=FST50, pointvector__y=Lucene50(blocksize=128), 
pointvector__x=BlockTreeOrds(blocksize=128), id=BlockTreeOrds(blocksize=128), 
srpt_quad=FSTOrd50, stqpt_geohash=FST50}, 
docValues:{bbox__maxX=DocValuesFormat(name=Memory), 
bbox__maxY=DocValuesFormat(name=Direct), 
bbox__minX=DocValuesFormat(name=Lucene50), 
bbox__minY=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=it, 
timezone=Europe/London
   [junit4]   2> NOTE: Linux 3.13.0-45-generic i386/Oracle Corporation 1.8.0_31 
(32-bit)/cpus=8,threads=3,free=53390352,total=212254720
   [junit4]   2> NOTE: All tests run in this JVM: [TestReload, 
HighlighterConfigTest, RAMDirectoryFactoryTest, TestFiltering, 
TestManagedSchemaFieldResource, FileBasedSpellCheckerTest, 
TestDynamicFieldResource, ResourceLoaderTest, 
DistributedQueryComponentCustomSortTest, DeleteInactiveReplicaTest, 
ParsingFieldUpdateProcessorsTest, TestFreeTextSuggestions, 
TestLMDirichletSimilarityFactory, TestBlendedInfixSuggestions, TestConfig, 
NotRequiredUniqueKeyTest, SuggesterTSTTest, CollectionsAPIDistributedZkTest, 
PolyFieldTest, TestDefaultSearchFieldResource, AnalyticsQueryTest, 
PrimitiveFieldTypeTest, TestLRUStatsCache, MoreLikeThisHandlerTest, 
DocumentBuilderTest, QueryResultKeyTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, TermVectorComponentTest, 
MBeansHandlerTest, OpenExchangeRatesOrgProviderTest, TestFieldResource, 
UUIDUpdateProcessorFallbackTest, TestRealTimeGet, HdfsBasicDistributedZk2Test, 
TestDFRSimilarityFactory, TestPseudoReturnFields, QueryParsingTest, 
ExternalFileFieldSortTest, TestRestManager, StatsComponentTest, 
DateMathParserTest, TestImplicitCoreProperties, HdfsBasicDistributedZkTest, 
FieldMutatingUpdateProcessorTest, TestCollationFieldDocValues, 
TestSystemIdResolver, PluginInfoTest, ConnectionManagerTest, SSLMigrationTest, 
TestRecovery, TestJoin, OverriddenZkACLAndCredentialsProvidersTest, 
SaslZkACLProviderTest, TestSolrDeletionPolicy1, DisMaxRequestHandlerTest, 
TestSchemaResource, DistributedExpandComponentTest, 
TestFuzzyAnalyzedSuggestions, TestTolerantSearch, 
HdfsChaosMonkeySafeLeaderTest, TestSolrDeletionPolicy2, TestReqParamsAPI, 
TestLMJelinekMercerSimilarityFactory, SolrIndexConfigTest, 
DistributedQueueTest, TestBadConfig, SolrTestCaseJ4Test, ZkSolrClientTest, 
ReplicationFactorTest, TestRecoveryHdfs, LeaderElectionIntegrationTest, 
CopyFieldTest, TestFieldTypeCollectionResource, DebugComponentTest, 
IndexBasedSpellCheckerTest, UpdateRequestProcessorFactoryTest, 
SpellCheckCollatorTest, BasicDistributedZk2Test, DirectSolrSpellCheckerTest, 
DateFieldTest, AtomicUpdatesTest, TermsComponentTest, TestFastWriter, 
AssignTest, HdfsLockFactoryTest, TestMergePolicyConfig, 
TestManagedSchemaDynamicFieldResource, RollingRestartTest, 
DirectUpdateHandlerOptimizeTest, DistributedTermsComponentTest, 
BasicFunctionalityTest, ExternalCollectionsTest, TestDefaultSimilarityFactory, 
TermVectorComponentDistributedTest, TestSortingResponseWriter, 
TestBulkSchemaAPI, TestValueSourceCache, FullSolrCloudDistribCmdsTest, 
RegexBoostProcessorTest, ZkNodePropsTest, SpellCheckComponentTest, 
AutoCommitTest, TestSuggestSpellingConverter, 
TestManagedSchemaFieldTypeResource, JsonLoaderTest, TestReplicaProperties, 
TestSolr4Spatial]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSolr4Spatial 
-Dtests.seed=CB8557CFFAC71400 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=it -Dtests.timezone=Europe/London -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J1 | TestSolr4Spatial (suite) <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: 
SolrCore.getOpenCount()==6
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([CB8557CFFAC71400]:0)
   [junit4]    >        at 
org.apache.solr.util.TestHarness.close(TestHarness.java:356)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:683)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:226)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE 
scope at org.apache.solr.search.TestSolr4Spatial: 
   [junit4]    >    1) Thread[id=5074, name=searcherExecutor-2496-thread-1, 
state=WAITING, group=TGRP-TestSolr4Spatial]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=5075, name=Thread-2314, state=WAITING, 
group=TGRP-TestSolr4Spatial]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.lang.Object.wait(Object.java:502)
   [junit4]    >         at 
org.apache.solr.core.CloserThread.run(CoreContainer.java:902)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([CB8557CFFAC71400]:0)Throwable #3: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=5074, name=searcherExecutor-2496-thread-1, 
state=WAITING, group=TGRP-TestSolr4Spatial]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]    >         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=5075, name=Thread-2314, state=WAITING, 
group=TGRP-TestSolr4Spatial]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.lang.Object.wait(Object.java:502)
   [junit4]    >         at 
org.apache.solr.core.CloserThread.run(CoreContainer.java:902)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([CB8557CFFAC71400]:0)
   [junit4] Completed on J1 in 29.35s, 45 tests, 3 errors, 2 skipped <<< 
FAILURES!

[...truncated 891 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:191: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:509: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1370: 
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:976: 
There were test failures: 468 suites, 1870 tests, 3 suite-level errors, 947 
ignored (15 assumptions)

Total time: 71 minutes 32 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_31 -client 
-XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


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

Reply via email to