You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/02/26 06:36:53 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.9.0-ea-b47) - Build #
11882 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11882/
Java: 32bit/jdk1.9.0-ea-b47 -client -XX:+UseG1GC
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([9D5B345CFDE272E6]: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.GeneratedMethodAccessor34.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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=870, name=searcherExecutor-488-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=871, name=Thread-306, 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=870, name=searcherExecutor-488-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=871, name=Thread-306, 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([9D5B345CFDE272E6]: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=870, name=searcherExecutor-488-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=871, name=Thread-306, 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=870, name=searcherExecutor-488-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=871, name=Thread-306, 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([9D5B345CFDE272E6]:0)
Build Log:
[...truncated 9109 lines...]
[junit4] Suite: org.apache.solr.search.TestSolr4Spatial
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial 9D5B345CFDE272E6-001/init-core-data-001
[junit4] 2> 182409 T868 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 182409 T868 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 182409 T868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 182410 T868 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 182410 T868 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 182411 T868 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 182433 T868 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 182437 T868 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 182444 T868 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 182444 T868 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema-spatial.xml
[junit4] 2> 182447 T868 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 182477 T868 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 182478 T868 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 182478 T868 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr
[junit4] 2> 182479 T868 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 182494 T868 oasc.CoreContainer.<init> New CoreContainer 2331907
[junit4] 2> 182495 T868 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 182495 T868 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/lib
[junit4] 2> 182495 T868 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/lib).
[junit4] 2> 182502 T868 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 600000
[junit4] 2> 182502 T868 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 182502 T868 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 60000
[junit4] 2> 182503 T868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 182503 T868 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 182503 T868 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 182503 T868 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 182503 T868 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 182504 T868 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 182504 T868 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 182504 T868 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 182505 T868 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 182505 T868 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 182505 T868 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 182505 T868 oasc.CoreContainer.load Node Name: testNode
[junit4] 2> 182507 T869 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 182507 T869 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 182507 T869 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 182508 T869 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 182528 T869 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 182531 T869 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 182538 T869 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 182538 T869 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema-spatial.xml
[junit4] 2> 182541 T869 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 182560 T869 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 182561 T869 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 182561 T869 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 182562 T869 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial 9D5B345CFDE272E6-001/init-core-data-001/
[junit4] 2> 182562 T869 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 182562 T869 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial 9D5B345CFDE272E6-001/init-core-data-001
[junit4] 2> 182563 T869 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial 9D5B345CFDE272E6-001/init-core-data-001/index/
[junit4] 2> 182563 T869 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial 9D5B345CFDE272E6-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 182563 T869 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestSolr4Spatial 9D5B345CFDE272E6-001/init-core-data-001/index
[junit4] 2> 182563 T869 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.780908429849234]
[junit4] 2> 182564 T869 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_1,generation=1}
[junit4] 2> 182564 T869 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 182565 T869 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 182566 T869 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 182566 T869 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 182566 T869 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 182566 T869 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 182567 T869 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 182567 T869 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 182567 T869 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 182567 T869 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 182568 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 182568 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 182568 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 182568 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 182569 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 182569 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 182569 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 182569 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 182570 T869 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 182570 T869 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 182572 T869 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 182573 T869 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 182574 T869 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 182575 T869 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 182575 T869 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 182576 T869 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 182576 T869 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 182576 T869 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=79.7001953125, floorSegmentMB=2.01953125, forceMergeDeletesPctAllowed=9.184351501389706, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 182577 T869 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_1,generation=1}
[junit4] 2> 182577 T869 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 182577 T869 oass.SolrIndexSearcher.<init> Opening Searcher@72c27c[collection1] main
[junit4] 2> 182578 T869 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 182578 T869 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 182578 T869 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 182578 T869 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 182578 T869 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 182579 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@72c27c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182579 T869 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 182581 T868 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 182583 T868 oas.SolrTestCaseJ4.setUp ###Starting testSort {p0=bbox}
[junit4] 2> ASYNC NEW_CORE C281 name=collection1 org.apache.solr.core.SolrCore@1feb1e6
[junit4] 2> 182584 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182584 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 182587 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182588 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_2,generation=2}
[junit4] 2> 182589 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 182590 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@efdea1[collection1] main
[junit4] 2> 182590 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182590 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@efdea1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182591 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 182600 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100]} 0 8
[junit4] 2> 182601 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[101]} 0 0
[junit4] 2> 182603 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[999]} 0 1
[junit4] 2> 182603 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182609 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_3,generation=3}
[junit4] 2> 182610 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 182612 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@189e2d0[collection1] main
[junit4] 2> 182612 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182613 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@189e2d0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c3)))}
[junit4] 2> 182613 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
[junit4] 2> 182625 T868 C281 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=11
[junit4] 2> 182635 T868 C281 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=8
[junit4] 2> 182638 T868 C281 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=2
[junit4] 2> 182640 T868 C281 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> 182642 T868 C281 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=1
[junit4] 2> 182649 T868 C281 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=7
[junit4] 2> 182651 T868 C281 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=1
[junit4] 2> 182652 T868 oas.SolrTestCaseJ4.tearDown ###Ending testSort {p0=bbox}
[junit4] 2> 182655 T868 oas.SolrTestCaseJ4.setUp ###Starting checkQueryEmptyIndex {p0=bbox}
[junit4] 2> 182656 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182656 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182657 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182657 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_4,generation=4}
[junit4] 2> 182658 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 182658 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1ddece4[collection1] main
[junit4] 2> 182659 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182659 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ddece4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182660 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 182660 T868 oas.SolrTestCaseJ4.tearDown ###Ending checkQueryEmptyIndex {p0=bbox}
[junit4] 2> 182663 T868 oas.SolrTestCaseJ4.setUp ###Starting testSortMultiVal {p0=bbox}
[junit4] 2> 182664 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182664 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 182665 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182666 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_5,generation=5}
[junit4] 2> 182666 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 182667 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1ced174[collection1] main
[junit4] 2> 182667 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182667 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ced174[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182668 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 182669 T868 oas.SolrTestCaseJ4.tearDown ###Ending testSortMultiVal {p0=bbox}
[junit4] IGNOR/A 0.03s J1 | TestSolr4Spatial.testSortMultiVal {p0=bbox}
[junit4] > Assumption #1: Multivalue not supported for this field
[junit4] 2> 182697 T868 oas.SolrTestCaseJ4.setUp ###Starting testRangeSyntax {p0=bbox}
[junit4] 2> 182697 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182698 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182698 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182699 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_6,generation=6}
[junit4] 2> 182699 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 182700 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1c4b64b[collection1] main
[junit4] 2> 182700 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182700 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c4b64b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182701 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 182703 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 2
[junit4] 2> 182704 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 1
[junit4] 2> 182705 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 1
[junit4] 2> 182706 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 1
[junit4] 2> 182706 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 0
[junit4] 2> 182707 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 0
[junit4] 2> 182708 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[7]} 0 0
[junit4] 2> 182709 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[8]} 0 0
[junit4] 2> 182710 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[9]} 0 0
[junit4] 2> 182711 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10]} 0 0
[junit4] 2> 182712 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 182713 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[12]} 0 0
[junit4] 2> 182714 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[13]} 0 0
[junit4] 2> 182715 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182722 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_7,generation=7}
[junit4] 2> 182723 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 182725 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1f94471[collection1] main
[junit4] 2> 182725 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182725 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f94471[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c13)))}
[junit4] 2> 182726 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 182731 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!+score%3Dnone+df%3Dbbox}[32,-80+TO+"33+,+-79"]&rows=1000&wt=xml} hits=1 status=0 QTime=4
[junit4] 2> 182732 T868 oas.SolrTestCaseJ4.tearDown ###Ending testRangeSyntax {p0=bbox}
[junit4] 2> 182735 T868 oas.SolrTestCaseJ4.setUp ###Starting checkResultFormat {p0=bbox}
[junit4] 2> 182735 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182736 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182737 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182737 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_8,generation=8}
[junit4] 2> 182737 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 182738 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1a2581[collection1] main
[junit4] 2> 182738 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182738 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a2581[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182739 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182741 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 1
[junit4] 2> 182741 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182746 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_9,generation=9}
[junit4] 2> 182746 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 182748 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@120e25d[collection1] main
[junit4] 2> 182749 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182749 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@120e25d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.0.0):c1)))}
[junit4] 2> 182749 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 182751 T868 C281 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=1
[junit4] 2> 182752 T868 oas.SolrTestCaseJ4.tearDown ###Ending checkResultFormat {p0=bbox}
[junit4] 2> 182755 T868 oas.SolrTestCaseJ4.setUp ###Starting testBadScoreParam {p0=bbox}
[junit4] 2> 182756 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182756 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182757 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182757 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_a,generation=10}
[junit4] 2> 182757 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 182758 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@178848a[collection1] main
[junit4] 2> 182758 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182758 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@178848a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182759 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182760 T868 C281 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 182760 T868 C281 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=0
[junit4] 2> 182761 T868 oas.SolrTestCaseJ4.tearDown ###Ending testBadScoreParam {p0=bbox}
[junit4] 2> 182763 T868 oas.SolrTestCaseJ4.setUp ###Starting testIntersectFilter {p0=bbox}
[junit4] 2> 182764 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182764 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 182765 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182765 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_b,generation=11}
[junit4] 2> 182766 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 182766 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1ca90d1[collection1] main
[junit4] 2> 182766 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182767 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ca90d1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182767 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182769 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 1
[junit4] 2> 182770 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 1
[junit4] 2> 182771 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 1
[junit4] 2> 182772 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 1
[junit4] 2> 182773 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 1
[junit4] 2> 182774 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 0
[junit4] 2> 182775 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[7]} 0 1
[junit4] 2> 182776 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[8]} 0 1
[junit4] 2> 182776 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[9]} 0 0
[junit4] 2> 182777 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10]} 0 0
[junit4] 2> 182779 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 182779 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[12]} 0 0
[junit4] 2> 182780 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[13]} 0 0
[junit4] 2> 182781 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182788 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_c,generation=12}
[junit4] 2> 182789 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 182791 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1dc37a6[collection1] main
[junit4] 2> 182791 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182791 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dc37a6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):c13)))}
[junit4] 2> 182792 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 182795 T868 C281 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=2
[junit4] 2> 182797 T868 C281 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=1
[junit4] 2> 182799 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182799 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182801 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[14]} 0 1
[junit4] 2> 182802 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[15]} 0 1
[junit4] 2> 182803 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[16]} 0 1
[junit4] 2> 182803 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[17]} 0 0
[junit4] 2> 182804 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182809 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_d,generation=13}
[junit4] 2> 182810 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 182812 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@19efb88[collection1] main
[junit4] 2> 182812 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182812 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19efb88[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c4)))}
[junit4] 2> 182813 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
[junit4] 2> 182815 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dbbox+score%3DrecipDistance}Intersects(ENVELOPE(-26.979611032849906,+26.979611032849906,+26.979611032849906,+-26.979611032849906))&rows=1000&wt=xml} hits=3 status=0 QTime=1
[junit4] 2> 182818 T868 C281 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=1
[junit4] 2> 182820 T868 C281 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=1
[junit4] 2> 182822 T868 C281 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=1
[junit4] 2> 182823 T868 oas.SolrTestCaseJ4.tearDown ###Ending testIntersectFilter {p0=bbox}
[junit4] 2> 182826 T868 oas.SolrTestCaseJ4.setUp ###Starting testSpatialConfig {p0=bbox}
[junit4] 2> 182827 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182827 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182828 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182828 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_e,generation=14}
[junit4] 2> 182828 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 182829 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1d6a39b[collection1] main
[junit4] 2> 182829 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182829 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d6a39b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182830 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182831 T868 oas.SolrTestCaseJ4.tearDown ###Ending testSpatialConfig {p0=bbox}
[junit4] 2> 182833 T868 oas.SolrTestCaseJ4.setUp ###Starting testBadShapeParse400 {p0=bbox}
[junit4] 2> 182834 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182834 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 182835 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182835 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_f,generation=15}
[junit4] 2> 182835 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 182836 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@f4521d[collection1] main
[junit4] 2> 182836 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182836 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f4521d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182837 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182838 T868 C281 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 182838 T868 C281 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=1
[junit4] 2> 182839 T868 C281 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 182840 T868 C281 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=1
[junit4] 2> 182840 T868 C281 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 182841 T868 C281 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> 182841 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {} 0 0
[junit4] 2> 182842 T868 C281 oasc.SolrException.log Ignoring exception matching NonexistentShape
[junit4] 2> 182842 T868 oas.SolrTestCaseJ4.tearDown ###Ending testBadShapeParse400 {p0=bbox}
[junit4] 2> 182845 T868 oas.SolrTestCaseJ4.setUp ###Starting testSort {p0=srpt_geohash}
[junit4] 2> 182846 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182846 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182847 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182847 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_g,generation=16}
[junit4] 2> 182848 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 182848 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1ac5514[collection1] main
[junit4] 2> 182848 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182849 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ac5514[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182849 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182852 T868 C281 oass.AbstractSpatialPrefixTreeFieldType.newSpatialStrategy srpt_geohash{class=org.apache.solr.schema.SpatialRecursivePrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={distanceUnits=degrees, class=solr.SpatialRecursivePrefixTreeFieldType, prefixTree=geohash}} strat: RecursivePrefixTreeStrategy(SPG:(GeohashPrefixTree(maxLevels:11,ctx:SpatialContext{geo=true, calculator=Haversine, worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)})),pruneLeafyBranches) maxLevels: 11
[junit4] 2> 182856 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100]} 0 6
[junit4] 2> 182857 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[101]} 0 0
[junit4] 2> 182858 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[999]} 0 0
[junit4] 2> 182859 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182861 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_g,generation=16}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_h,generation=17}
[junit4] 2> 182861 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
[junit4] 2> 182862 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1722770[collection1] main
[junit4] 2> 182862 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182862 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1722770[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.0.0):c3)))}
[junit4] 2> 182863 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 182867 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!+++}srpt_geohash:"Intersects(BUFFER(POINT(4.0+3.0),9.0))"&fl=id,score&wt=xml} hits=2 status=0 QTime=3
[junit4] 2> 182871 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!geofilt+sfield%3Dsrpt_geohash+score%3Ddistance++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&sort=score+asc&wt=xml} hits=2 status=0 QTime=3
[junit4] 2> 182874 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!geofilt+sfield%3Dsrpt_geohash+score%3DrecipDistance++pt%3D3.0,4.0+d%3D9.0}&fl=id,score&sort=score+desc&wt=xml} hits=2 status=0 QTime=2
[junit4] 2> 182874 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!+score%3Ddistance+filter%3Dfalse+}srpt_geohash:"Intersects(BUFFER(POINT(4.0+3.0),1.0E-6))"&fl=id,score&sort=score+asc&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 182878 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!geofilt+sfield%3Dsrpt_geohash+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> 182880 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=-id:999&fl=id,score&sort=query($sortQuery)+asc&wt=xml&sortQuery={!geofilt+sfield%3Dsrpt_geohash+score%3Ddistance++pt%3D3.0,4.0+d%3D9.0}} hits=2 status=0 QTime=2
[junit4] 2> 182883 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=-id:999&fl=id,score&sort=query($sortQuery)+asc&wt=xml&sortQuery={!+score%3Ddistance++}srpt_geohash:"Intersects(BUFFER(POINT(0.0+4.0),9.0))"} hits=2 status=0 QTime=3
[junit4] 2> 182883 T868 oas.SolrTestCaseJ4.tearDown ###Ending testSort {p0=srpt_geohash}
[junit4] 2> 182887 T868 oas.SolrTestCaseJ4.setUp ###Starting checkQueryEmptyIndex {p0=srpt_geohash}
[junit4] 2> 182887 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182887 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 182888 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182889 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_h,generation=17}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_i,generation=18}
[junit4] 2> 182889 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
[junit4] 2> 182889 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@12699b5[collection1] main
[junit4] 2> 182890 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182890 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12699b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182890 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182891 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(0.0+0.0),0.8993203677616635))&rows=1000&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 182892 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'0,0'+d%3D100.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 182893 T868 oas.SolrTestCaseJ4.tearDown ###Ending checkQueryEmptyIndex {p0=srpt_geohash}
[junit4] 2> 182896 T868 oas.SolrTestCaseJ4.setUp ###Starting testSortMultiVal {p0=srpt_geohash}
[junit4] 2> 182896 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182896 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 182897 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182898 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_j,generation=19}
[junit4] 2> 182898 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
[junit4] 2> 182898 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@8ee92a[collection1] main
[junit4] 2> 182899 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182899 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8ee92a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182899 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182901 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100]} 0 0
[junit4] 2> 182903 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[101]} 0 1
[junit4] 2> 182903 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182905 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_j,generation=19}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_k,generation=20}
[junit4] 2> 182905 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
[junit4] 2> 182906 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1579a9b[collection1] main
[junit4] 2> 182907 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182907 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1579a9b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(6.0.0):c2)))}
[junit4] 2> 182907 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 182909 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!+score%3Ddistance++}srpt_geohash:"Intersects(BUFFER(POINT(4.0+3.0),9.0))"&fl=id,score&sort=score+asc&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 182910 T868 oas.SolrTestCaseJ4.tearDown ###Ending testSortMultiVal {p0=srpt_geohash}
[junit4] 2> 182913 T868 oas.SolrTestCaseJ4.setUp ###Starting testRangeSyntax {p0=srpt_geohash}
[junit4] 2> 182913 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182914 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182914 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182915 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_k,generation=20}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_l,generation=21}
[junit4] 2> 182915 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
[junit4] 2> 182916 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@e2ed5d[collection1] main
[junit4] 2> 182916 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182916 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e2ed5d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182917 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 182918 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 1
[junit4] 2> 182919 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 0
[junit4] 2> 182920 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 0
[junit4] 2> 182921 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 0
[junit4] 2> 182922 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 1
[junit4] 2> 182923 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 1
[junit4] 2> 182923 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[7]} 0 0
[junit4] 2> 182924 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[8]} 0 0
[junit4] 2> 182925 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[9]} 0 0
[junit4] 2> 182926 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10]} 0 0
[junit4] 2> 182927 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 182928 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[12]} 0 0
[junit4] 2> 182929 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[13]} 0 0
[junit4] 2> 182930 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182932 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_l,generation=21}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_m,generation=22}
[junit4] 2> 182933 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
[junit4] 2> 182934 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@f9b10f[collection1] main
[junit4] 2> 182934 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182934 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f9b10f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(6.0.0):c13)))}
[junit4] 2> 182935 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 182937 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!+score%3Dnone+df%3Dsrpt_geohash}[32,-80+TO+"33+,+-79"]&rows=1000&wt=xml} hits=1 status=0 QTime=1
[junit4] 2> 182938 T868 oas.SolrTestCaseJ4.tearDown ###Ending testRangeSyntax {p0=srpt_geohash}
[junit4] 2> 182941 T868 oas.SolrTestCaseJ4.setUp ###Starting checkResultFormat {p0=srpt_geohash}
[junit4] 2> 182942 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182942 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182943 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182943 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_m,generation=22}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_n,generation=23}
[junit4] 2> 182944 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
[junit4] 2> 182944 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@13e295d[collection1] main
[junit4] 2> 182945 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182945 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13e295d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182945 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182946 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 182947 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182949 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_n,generation=23}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_o,generation=24}
[junit4] 2> 182949 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
[junit4] 2> 182950 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@31e518[collection1] main
[junit4] 2> 182950 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182950 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@31e518[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(6.0.0):c1)))}
[junit4] 2> 182951 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 182952 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id,srpt_geohash&fq={!bbox+sfield%3Dsrpt_geohash+pt%3D89.9,-130+d%3D9}&rows=1000&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 182953 T868 oas.SolrTestCaseJ4.tearDown ###Ending checkResultFormat {p0=srpt_geohash}
[junit4] 2> 182956 T868 oas.SolrTestCaseJ4.setUp ###Starting testBadScoreParam {p0=srpt_geohash}
[junit4] 2> 182956 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182957 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 182958 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182958 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_o,generation=24}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_p,generation=25}
[junit4] 2> 182958 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
[junit4] 2> 182959 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1b72388[collection1] main
[junit4] 2> 182959 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182959 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b72388[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182960 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182961 T868 C281 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 182961 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!geofilt+sfield%3Dsrpt_geohash+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> 182961 T868 oas.SolrTestCaseJ4.tearDown ###Ending testBadScoreParam {p0=srpt_geohash}
[junit4] 2> 182964 T868 oas.SolrTestCaseJ4.setUp ###Starting testIntersectFilter {p0=srpt_geohash}
[junit4] 2> 182965 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 182965 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 182966 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182966 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_p,generation=25}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_q,generation=26}
[junit4] 2> 182967 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
[junit4] 2> 182967 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@b255c3[collection1] main
[junit4] 2> 182967 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182968 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b255c3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 182968 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 2
[junit4] 2> 182969 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 0
[junit4] 2> 182970 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 0
[junit4] 2> 182971 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 0
[junit4] 2> 182972 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 0
[junit4] 2> 182973 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 0
[junit4] 2> 182974 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 0
[junit4] 2> 182975 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[7]} 0 1
[junit4] 2> 182976 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[8]} 0 1
[junit4] 2> 182976 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[9]} 0 0
[junit4] 2> 182977 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10]} 0 0
[junit4] 2> 182978 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 182979 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[12]} 0 0
[junit4] 2> 182980 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[13]} 0 0
[junit4] 2> 182980 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 182983 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_q,generation=26}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_r,generation=27}
[junit4] 2> 182984 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
[junit4] 2> 182985 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@1aa9b07[collection1] main
[junit4] 2> 182985 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 182985 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1aa9b07[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.0.0):c13)))}
[junit4] 2> 182986 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 182988 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Dnone}Intersects(BUFFER(POINT(1.0+1.0),1.5738106435829113))&rows=1000&wt=xml} hits=3 status=0 QTime=2
[junit4] 2> 182991 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'1,1'+d%3D175.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=3 status=0 QTime=2
[junit4] 2> 182996 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3DrecipDistance}Intersects(BUFFER(POINT(179.8+0.0),1.798640735523327))&rows=1000&wt=xml} hits=2 status=0 QTime=4
[junit4] 2> 182999 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'0,179.8'+d%3D200.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=2 status=0 QTime=2
[junit4] 2> 183001 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3DrecipDistance}Intersects(BUFFER(POINT(50.0+89.8),1.798640735523327))&rows=1000&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 183002 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'89.8,+50'+d%3D200.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 183004 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(50.0+-89.8),1.798640735523327))&rows=1000&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 183007 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'-89.8,+50'+d%3D200.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 183008 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(-80.0+33.0),2.6979611032849906))&rows=1000&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 183010 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'33.0,-80.0'+d%3D300.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 183012 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash}Intersects(BUFFER(POINT(1.0+1.0),44.96601838808318))&rows=1000&wt=xml} hits=3 status=0 QTime=2
[junit4] 2> 183015 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'1,1'+d%3D5000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=3 status=0 QTime=1
[junit4] 2> 183018 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Dnone}Intersects(ENVELOPE(-0.034898260626377464,+0.23489826062637748,+0.23489805516424955,+-0.03489805516424954))&rows=1000&wt=xml} hits=2 status=0 QTime=2
[junit4] 2> 183020 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!bbox+sfield%3Dsrpt_geohash+pt%3D'0.1,0.1'+d%3D15.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=2 status=0 QTime=2
[junit4] 2> 183021 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 183021 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 183023 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[14]} 0 1
[junit4] 2> 183023 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[15]} 0 0
[junit4] 2> 183024 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[16]} 0 0
[junit4] 2> 183025 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[17]} 0 0
[junit4] 2> 183026 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 183028 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_r,generation=27}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_s,generation=28}
[junit4] 2> 183028 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
[junit4] 2> 183029 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@fd4eb2[collection1] main
[junit4] 2> 183029 T868 C281 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 183030 T870 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fd4eb2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.0.0):c4)))}
[junit4] 2> 183030 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 183032 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(0.0+0.0),8.993203677616636))&rows=1000&wt=xml} hits=1 status=0 QTime=1
[junit4] 2> 183033 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'0,0'+d%3D1000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 183035 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash}Intersects(BUFFER(POINT(0.0+0.0),17.986407355233272))&rows=1000&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 183037 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'0,0'+d%3D2000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 183038 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3DrecipDistance}Intersects(ENVELOPE(-26.979611032849906,+26.979611032849906,+26.979611032849906,+-26.979611032849906))&rows=1000&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 183040 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!bbox+sfield%3Dsrpt_geohash+pt%3D'0,0'+d%3D3000.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=3 status=0 QTime=1
[junit4] 2> 183042 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(0.0+0.0),26.98860423652752))&rows=1000&wt=xml} hits=3 status=0 QTime=1
[junit4] 2> 183044 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'0,0'+d%3D3001.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=3 status=0 QTime=1
[junit4] 2> 183046 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3DrecipDistance}Intersects(BUFFER(POINT(0.0+0.0),26.980510353217664))&rows=1000&wt=xml} hits=3 status=0 QTime=1
[junit4] 2> 183048 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'0,0'+d%3D3000.1+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 183050 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash}Intersects(BUFFER(POINT(-96.789603+43.51703),0.9802592008602133))&rows=1000&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 183051 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'43.517030,-96.789603'+d%3D109.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 183053 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Dnone}Intersects(BUFFER(POINT(-96.789603+43.51703),0.9892524045378299))&rows=1000&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 183054 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!geofilt+sfield%3Dsrpt_geohash+pt%3D'43.517030,-96.789603'+d%3D110.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 183055 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dsrpt_geohash+score%3Dnone}Intersects(ENVELOPE(-98.15383081481679,+-95.42537518518321,+44.50628240453783,+42.52777759546217))&rows=1000&wt=xml} hits=1 status=0 QTime=1
[junit4] 2> 183057 T868 C281 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!bbox+sfield%3Dsrpt_geohash+pt%3D'43.517030,-96.789603'+d%3D110.0+sphere_radius%3D6371.0087714}&rows=1000&wt=xml} hits=1 status=0 QTime=1
[junit4] 2> 183058 T868 oas.SolrTestCaseJ4.tearDown ###Ending testIntersectFilter {p0=srpt_geohash}
[junit4] 2> 183060 T868 oas.SolrTestCaseJ4.setUp ###Starting testSpatialConfig {p0=srpt_geohash}
[junit4] 2> 183061 T868 C281 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 183061 T868 C281 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 183062 T868 C281 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 183062 T868 C281 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_s,generation=28}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ce837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1823bf2),segFN=segments_t,generation=29}
[junit4] 2> 183063 T868 C281 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
[junit4] 2> 183063 T868 C281 oass.SolrIndexSearcher.<init> Opening Searcher@17f20fa[collection1] main
[junit4] 2> 183064 T868 C281 oasu.DirectUpdateHandler2.commi
[...truncated too long message...]
eadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> 25520 T867 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 2 threads leaked from SUITE scope at org.apache.solr.search.TestSolr4Spatial:
[junit4] 2> 1) Thread[id=870, name=searcherExecutor-488-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=871, name=Thread-306, 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> 25521 T867 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=870, name=searcherExecutor-488-thread-1, state=WAITING, group=TGRP-TestSolr4Spatial]
[junit4] 2> 2) Thread[id=871, name=Thread-306, state=WAITING, group=TGRP-TestSolr4Spatial]
[junit4] 2> 28529 T867 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=870, name=searcherExecutor-488-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=871, name=Thread-306, 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=PostingsFormat(name=Memory doPackFST= false), srpt_geohash=PostingsFormat(name=Memory doPackFST= false), bbox__maxX=BlockTreeOrds(blocksize=128), bbox__maxY=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), bbox__minX=PostingsFormat(name=Memory doPackFST= false), bbox__minY=Lucene50(blocksize=128), pointvector__y=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), pointvector__x=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=Memory doPackFST= false), srpt_quad=PostingsFormat(name=Memory doPackFST= false), stqpt_geohash=Lucene50(blocksize=128)}, docValues:{bbox__maxX=DocValuesFormat(name=Direct), bbox__maxY=DocValuesFormat(name=Memory), bbox__minX=DocValuesFormat(name=Asserting), bbox__minY=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=zh_SG, timezone=Indian/Reunion
[junit4] 2> NOTE: Linux 3.13.0-45-generic i386/Oracle Corporation 1.9.0-ea (32-bit)/cpus=8,threads=3,free=121044944,total=301989888
[junit4] 2> NOTE: All tests run in this JVM: [TestSortingResponseWriter, TestNRTOpen, TestJoin, TestFreeTextSuggestions, RAMDirectoryFactoryTest, DateRangeFieldTest, PluginInfoTest, TestTrie, TestClusterStateMutator, SpellCheckComponentTest, TestNoOpRegenerator, TestFileDictionaryLookup, QueryResultKeyTest, DistributedExpandComponentTest, MigrateRouteKeyTest, TestReplicaProperties, TestCoreDiscovery, TestImplicitCoreProperties, SolrTestCaseJ4Test, ExternalCollectionsTest, PolyFieldTest, TestPHPSerializedResponseWriter, TestSolr4Spatial]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolr4Spatial -Dtests.seed=9D5B345CFDE272E6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh_SG -Dtests.timezone=Indian/Reunion -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([9D5B345CFDE272E6]: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=870, name=searcherExecutor-488-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=871, name=Thread-306, 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([9D5B345CFDE272E6]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=870, name=searcherExecutor-488-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=871, name=Thread-306, 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([9D5B345CFDE272E6]:0)
[junit4] Completed on J1 in 28.60s, 45 tests, 3 errors, 2 skipped <<< FAILURES!
[...truncated 1289 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:191: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1351: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:958: There were test failures: 468 suites, 1868 tests, 3 suite-level errors, 1323 ignored (15 assumptions)
Total time: 57 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.9.0-ea-b47 -client -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.9.0-ea-b47) - Build #
11883 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11883/
Java: 32bit/jdk1.9.0-ea-b47 -client -XX:+UseG1GC
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([3FA4C8399F64215A]: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.GeneratedMethodAccessor32.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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=11022, name=Thread-3973, 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) 2) Thread[id=11021, name=searcherExecutor-4338-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)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.search.TestSolr4Spatial:
1) Thread[id=11022, name=Thread-3973, 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)
2) Thread[id=11021, name=searcherExecutor-4338-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)
at __randomizedtesting.SeedInfo.seed([3FA4C8399F64215A]: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=11022, name=Thread-3973, 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) 2) Thread[id=11021, name=searcherExecutor-4338-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)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=11022, name=Thread-3973, 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)
2) Thread[id=11021, name=searcherExecutor-4338-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)
at __randomizedtesting.SeedInfo.seed([3FA4C8399F64215A]:0)
Build Log:
[...truncated 9782 lines...]
[junit4] Suite: org.apache.solr.search.TestSolr4Spatial
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestSolr4Spatial 3FA4C8399F64215A-001/init-core-data-001
[junit4] 2> 1575509 T11019 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1575510 T11019 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 1575510 T11019 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1575511 T11019 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1575511 T11019 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1575511 T11019 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1575536 T11019 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1575540 T11019 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1575547 T11019 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1575548 T11019 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema-spatial.xml
[junit4] 2> 1575552 T11019 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 1575564 T11019 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1575565 T11019 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1575566 T11019 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr
[junit4] 2> 1575566 T11019 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 1575584 T11019 oasc.CoreContainer.<init> New CoreContainer 18406532
[junit4] 2> 1575584 T11019 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 1575584 T11019 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/lib
[junit4] 2> 1575585 T11019 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/lib).
[junit4] 2> 1575592 T11019 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 600000
[junit4] 2> 1575592 T11019 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1575592 T11019 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 60000
[junit4] 2> 1575593 T11019 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1575593 T11019 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1575593 T11019 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1575594 T11019 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1575594 T11019 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1575594 T11019 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1575595 T11019 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1575595 T11019 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1575596 T11019 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 1575596 T11019 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1575597 T11019 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1575597 T11019 oasc.CoreContainer.load Node Name: testNode
[junit4] 2> 1575598 T11020 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1575599 T11020 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1575599 T11020 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1575599 T11020 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1575618 T11020 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1575623 T11020 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1575632 T11020 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1575632 T11020 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema-spatial.xml
[junit4] 2> 1575636 T11020 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1575646 T11020 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1575647 T11020 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 1575647 T11020 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1575648 T11020 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestSolr4Spatial 3FA4C8399F64215A-001/init-core-data-001/
[junit4] 2> 1575648 T11020 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1575648 T11020 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestSolr4Spatial 3FA4C8399F64215A-001/init-core-data-001
[junit4] 2> 1575649 T11020 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestSolr4Spatial 3FA4C8399F64215A-001/init-core-data-001/index/
[junit4] 2> 1575649 T11020 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestSolr4Spatial 3FA4C8399F64215A-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 1575649 T11020 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestSolr4Spatial 3FA4C8399F64215A-001/init-core-data-001/index
[junit4] 2> 1575650 T11020 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=13, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=42.3154296875, floorSegmentMB=0.458984375, forceMergeDeletesPctAllowed=7.774919894872932, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6150451347876604
[junit4] 2> 1575651 T11020 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_1,generation=1}
[junit4] 2> 1575651 T11020 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1575652 T11020 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1575652 T11020 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1575652 T11020 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1575653 T11020 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1575653 T11020 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1575653 T11020 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1575654 T11020 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1575654 T11020 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1575654 T11020 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1575655 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1575655 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1575655 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1575656 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1575656 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1575656 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1575657 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1575657 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 1575657 T11020 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1575658 T11020 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1575659 T11020 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1575660 T11020 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1575660 T11020 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1575661 T11020 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1575662 T11020 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1575662 T11020 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1575663 T11020 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1575663 T11020 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3319064062124627]
[junit4] 2> 1575664 T11020 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_1,generation=1}
[junit4] 2> 1575664 T11020 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1575664 T11020 oass.SolrIndexSearcher.<init> Opening Searcher@4dd304[collection1] main
[junit4] 2> 1575664 T11020 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1575665 T11020 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1575665 T11020 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1575665 T11020 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1575665 T11020 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1575666 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4dd304[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575666 T11020 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1575667 T11019 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 1575670 T11019 oas.SolrTestCaseJ4.setUp ###Starting testSort {p0=bbox}
[junit4] 2> ASYNC NEW_CORE C4389 name=collection1 org.apache.solr.core.SolrCore@ee2688
[junit4] 2> 1575670 T11019 C4389 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575671 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575673 T11019 C4389 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575674 T11019 C4389 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_2,generation=2}
[junit4] 2> 1575675 T11019 C4389 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1575675 T11019 C4389 oass.SolrIndexSearcher.<init> Opening Searcher@1e5f9ad[collection1] main
[junit4] 2> 1575676 T11019 C4389 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575676 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e5f9ad[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575677 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1575678 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100]} 0 1
[junit4] 2> 1575679 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[101]} 0 0
[junit4] 2> 1575680 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[999]} 0 1
[junit4] 2> 1575680 T11019 C4389 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575683 T11019 C4389 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_3,generation=3}
[junit4] 2> 1575683 T11019 C4389 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1575702 T11019 C4389 oass.SolrIndexSearcher.<init> Opening Searcher@116fcfb[collection1] main
[junit4] 2> 1575703 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@116fcfb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C3)))}
[junit4] 2> 1575703 T11019 C4389 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575704 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 24
[junit4] 2> 1575709 T11019 C4389 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=4
[junit4] 2> 1575715 T11019 C4389 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=5
[junit4] 2> 1575719 T11019 C4389 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> 1575720 T11019 C4389 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=0
[junit4] 2> 1575722 T11019 C4389 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=1
[junit4] 2> 1575724 T11019 C4389 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=1
[junit4] 2> 1575726 T11019 C4389 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=1
[junit4] 2> 1575727 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testSort {p0=bbox}
[junit4] 2> 1575732 T11019 oas.SolrTestCaseJ4.setUp ###Starting testSortMultiVal {p0=bbox}
[junit4] 2> 1575732 T11019 C4389 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575733 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575734 T11019 C4389 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575735 T11019 C4389 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_4,generation=4}
[junit4] 2> 1575735 T11019 C4389 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 1575736 T11019 C4389 oass.SolrIndexSearcher.<init> Opening Searcher@1a5441[collection1] main
[junit4] 2> 1575736 T11019 C4389 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575736 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a5441[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575737 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1575738 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testSortMultiVal {p0=bbox}
[junit4] IGNOR/A 0.02s J0 | TestSolr4Spatial.testSortMultiVal {p0=bbox}
[junit4] > Assumption #1: Multivalue not supported for this field
[junit4] 2> 1575748 T11019 oas.SolrTestCaseJ4.setUp ###Starting checkQueryEmptyIndex {p0=bbox}
[junit4] 2> 1575749 T11019 C4389 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575749 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 1575750 T11019 C4389 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575751 T11019 C4389 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_5,generation=5}
[junit4] 2> 1575752 T11019 C4389 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 1575752 T11019 C4389 oass.SolrIndexSearcher.<init> Opening Searcher@f72deb[collection1] main
[junit4] 2> 1575753 T11019 C4389 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575753 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f72deb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575754 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1575755 T11019 oas.SolrTestCaseJ4.tearDown ###Ending checkQueryEmptyIndex {p0=bbox}
[junit4] 2> 1575760 T11019 oas.SolrTestCaseJ4.setUp ###Starting testIntersectFilter {p0=bbox}
[junit4] 2> 1575760 T11019 C4389 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575761 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575762 T11019 C4389 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575763 T11019 C4389 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_6,generation=6}
[junit4] 2> 1575763 T11019 C4389 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 1575764 T11019 C4389 oass.SolrIndexSearcher.<init> Opening Searcher@1928e43[collection1] main
[junit4] 2> 1575765 T11019 C4389 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575765 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1928e43[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575765 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1575767 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 1
[junit4] 2> 1575768 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 0
[junit4] 2> 1575769 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 0
[junit4] 2> 1575770 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 0
[junit4] 2> 1575771 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 0
[junit4] 2> 1575772 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 0
[junit4] 2> 1575773 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[7]} 0 0
[junit4] 2> 1575774 T11019 C4389 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[8]} 0 0
[junit4] 2> ASYNC NEW_CORE C4390 name=collection1 org.apache.solr.core.SolrCore@ee2688
[junit4] 2> 1575784 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[9]} 0 1
[junit4] 2> 1575786 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10]} 0 0
[junit4] 2> 1575787 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 1575788 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[12]} 0 0
[junit4] 2> 1575789 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[13]} 0 0
[junit4] 2> 1575790 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575793 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_7,generation=7}
[junit4] 2> 1575794 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 1575796 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@1370718[collection1] main
[junit4] 2> 1575796 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575796 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1370718[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C13)))}
[junit4] 2> 1575797 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 1575801 T11019 C4390 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=2
[junit4] 2> 1575804 T11019 C4390 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=1
[junit4] 2> 1575806 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575807 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575808 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[14]} 0 1
[junit4] 2> 1575809 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[15]} 0 0
[junit4] 2> 1575810 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[16]} 0 0
[junit4] 2> 1575811 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[17]} 0 0
[junit4] 2> 1575812 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575815 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_8,generation=8}
[junit4] 2> 1575815 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 1575817 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@1900e4d[collection1] main
[junit4] 2> 1575818 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575818 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1900e4d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.0.0):C4)))}
[junit4] 2> 1575819 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 1575821 T11019 C4390 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=1
[junit4] 2> 1575823 T11019 C4390 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=0
[junit4] 2> 1575826 T11019 C4390 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dbbox+score%3Ddistance}Intersects(ENVELOPE(-98.15383081481679,+-95.42537518518321,+44.50628240453783,+42.52777759546217))&rows=1000&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1575828 T11019 C4390 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=0
[junit4] 2> 1575830 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testIntersectFilter {p0=bbox}
[junit4] 2> 1575835 T11019 oas.SolrTestCaseJ4.setUp ###Starting testSpatialConfig {p0=bbox}
[junit4] 2> 1575835 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575836 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575837 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575837 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_9,generation=9}
[junit4] 2> 1575838 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 1575839 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@f783c[collection1] main
[junit4] 2> 1575839 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575839 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f783c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575840 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1575841 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testSpatialConfig {p0=bbox}
[junit4] 2> 1575846 T11019 oas.SolrTestCaseJ4.setUp ###Starting checkResultFormat {p0=bbox}
[junit4] 2> 1575846 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575847 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575848 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575848 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_a,generation=10}
[junit4] 2> 1575849 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 1575850 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@1fa974e[collection1] main
[junit4] 2> 1575850 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575850 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fa974e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575851 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1575853 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 1
[junit4] 2> 1575853 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575856 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_b,generation=11}
[junit4] 2> 1575857 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 1575858 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@e2f6b7[collection1] main
[junit4] 2> 1575859 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575859 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e2f6b7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C1)))}
[junit4] 2> 1575860 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 1575862 T11019 C4390 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=1
[junit4] 2> 1575863 T11019 oas.SolrTestCaseJ4.tearDown ###Ending checkResultFormat {p0=bbox}
[junit4] 2> 1575867 T11019 oas.SolrTestCaseJ4.setUp ###Starting testBadShapeParse400 {p0=bbox}
[junit4] 2> 1575868 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575868 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575869 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575870 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_c,generation=12}
[junit4] 2> 1575870 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 1575871 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@777818[collection1] main
[junit4] 2> 1575872 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575872 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@777818[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575873 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1575875 T11019 C4390 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 1575875 T11019 C4390 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=1
[junit4] 2> 1575877 T11019 C4390 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 1575877 T11019 C4390 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=1
[junit4] 2> 1575878 T11019 C4390 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 1575879 T11019 C4390 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> 1575879 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {} 0 0
[junit4] 2> 1575881 T11019 C4390 oasc.SolrException.log Ignoring exception matching NonexistentShape
[junit4] 2> 1575881 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testBadShapeParse400 {p0=bbox}
[junit4] 2> 1575886 T11019 oas.SolrTestCaseJ4.setUp ###Starting testBadScoreParam {p0=bbox}
[junit4] 2> 1575886 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575887 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1575888 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575889 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_d,generation=13}
[junit4] 2> 1575889 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 1575890 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@10effd1[collection1] main
[junit4] 2> 1575890 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575890 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10effd1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575891 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1575893 T11019 C4390 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 1575893 T11019 C4390 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> 1575894 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testBadScoreParam {p0=bbox}
[junit4] 2> 1575898 T11019 oas.SolrTestCaseJ4.setUp ###Starting testRangeSyntax {p0=bbox}
[junit4] 2> 1575899 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575899 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 1575900 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575901 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_e,generation=14}
[junit4] 2> 1575902 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 1575902 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@15cf8ea[collection1] main
[junit4] 2> 1575903 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575903 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15cf8ea[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575904 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1575906 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 1
[junit4] 2> 1575907 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 1
[junit4] 2> 1575907 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 0
[junit4] 2> 1575908 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 0
[junit4] 2> 1575909 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 0
[junit4] 2> 1575910 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 0
[junit4] 2> 1575911 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[7]} 0 0
[junit4] 2> 1575912 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[8]} 0 0
[junit4] 2> 1575913 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[9]} 0 0
[junit4] 2> 1575914 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10]} 0 0
[junit4] 2> 1575915 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 1575916 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[12]} 0 0
[junit4] 2> 1575917 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[13]} 0 1
[junit4] 2> 1575917 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575921 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_f,generation=15}
[junit4] 2> 1575922 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 1575923 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@102675[collection1] main
[junit4] 2> 1575924 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575924 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@102675[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):C13)))}
[junit4] 2> 1575925 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 1575927 T11019 C4390 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!+score%3Dnone+df%3Dbbox}[32,-80+TO+"33+,+-79"]&rows=1000&wt=xml} hits=1 status=0 QTime=2
[junit4] 2> 1575928 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testRangeSyntax {p0=bbox}
[junit4] 2> 1575932 T11019 oas.SolrTestCaseJ4.setUp ###Starting testSort {p0=stqpt_geohash}
[junit4] 2> 1575933 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1575933 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 1575934 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575935 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_g,generation=16}
[junit4] 2> 1575936 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 1575936 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@c7c02[collection1] main
[junit4] 2> 1575937 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575937 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c7c02[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575938 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1575939 T11019 C4390 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> 1575941 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100]} 0 2
[junit4] 2> 1575942 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[101]} 0 1
[junit4] 2> 1575943 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[999]} 0 1
[junit4] 2> 1575943 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1575945 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_g,generation=16}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_h,generation=17}
[junit4] 2> 1575946 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
[junit4] 2> 1575947 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@1109e4a[collection1] main
[junit4] 2> 1575948 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1575948 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1109e4a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.0.0):C3)))}
[junit4] 2> 1575949 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 1576032 T11019 C4390 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=82
[junit4] 2> 1576092 T11019 C4390 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=59
[junit4] 2> 1576128 T11019 C4390 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!+score%3DrecipDistance++}stqpt_geohash:"Intersects(BUFFER(POINT(4.0+3.0),9.0))"&fl=id,score&sort=score+desc&wt=xml} hits=2 status=0 QTime=35
[junit4] 2> 1576130 T11019 C4390 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!geofilt+sfield%3Dstqpt_geohash+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=0
[junit4] 2> 1576167 T11019 C4390 oasc.SolrCore.execute [collection1] webapp=null path=null params={q={!+score%3Ddistance++}stqpt_geohash:"Intersects(BUFFER(POINT(0.0+4.0),9.0))"&fl=id,score&sort=score+asc&wt=xml} hits=2 status=0 QTime=36
[junit4] 2> 1576224 T11019 C4390 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=56
[junit4] 2> 1576266 T11019 C4390 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(0.0+4.0),9.0))"} hits=2 status=0 QTime=41
[junit4] 2> 1576268 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testSort {p0=stqpt_geohash}
[junit4] 2> 1576273 T11019 oas.SolrTestCaseJ4.setUp ###Starting testSortMultiVal {p0=stqpt_geohash}
[junit4] 2> 1576274 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1576274 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 1576276 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1576276 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_h,generation=17}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_i,generation=18}
[junit4] 2> 1576277 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
[junit4] 2> 1576278 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@f6623b[collection1] main
[junit4] 2> 1576278 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f6623b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1576279 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1576279 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1576281 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100]} 0 1
[junit4] 2> 1576282 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[101]} 0 0
[junit4] 2> 1576283 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1576285 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_j,generation=19}
[junit4] 2> 1576286 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
[junit4] 2> 1576288 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@a587c1[collection1] main
[junit4] 2> 1576288 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a587c1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(6.0.0):C2)))}
[junit4] 2> 1576289 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1576289 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 1576326 T11019 C4390 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=36
[junit4] 2> 1576327 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testSortMultiVal {p0=stqpt_geohash}
[junit4] 2> 1576332 T11019 oas.SolrTestCaseJ4.setUp ###Starting checkQueryEmptyIndex {p0=stqpt_geohash}
[junit4] 2> 1576332 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1576333 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1576334 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1576334 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_j,generation=19}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_k,generation=20}
[junit4] 2> 1576335 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
[junit4] 2> 1576336 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@130bc01[collection1] main
[junit4] 2> 1576336 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1576336 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@130bc01[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1576337 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1576360 T11019 C4390 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),0.8993203677616635))&rows=1000&wt=xml} hits=0 status=0 QTime=22
[junit4] 2> 1576382 T11019 C4390 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=14
[junit4] 2> 1576383 T11019 oas.SolrTestCaseJ4.tearDown ###Ending checkQueryEmptyIndex {p0=stqpt_geohash}
[junit4] 2> 1576388 T11019 oas.SolrTestCaseJ4.setUp ###Starting testIntersectFilter {p0=stqpt_geohash}
[junit4] 2> 1576389 T11019 C4390 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1576390 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1576391 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1576391 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_k,generation=20}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_l,generation=21}
[junit4] 2> 1576392 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
[junit4] 2> 1576393 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@effadb[collection1] main
[junit4] 2> 1576393 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1576393 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@effadb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1576394 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1576396 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 1
[junit4] 2> 1576397 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 1
[junit4] 2> 1576398 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 1
[junit4] 2> 1576399 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 1
[junit4] 2> 1576400 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 1
[junit4] 2> 1576401 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 1
[junit4] 2> 1576402 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[7]} 0 1
[junit4] 2> 1576403 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[8]} 0 1
[junit4] 2> 1576404 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[9]} 0 1
[junit4] 2> 1576404 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10]} 0 0
[junit4] 2> 1576405 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 1576406 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[12]} 0 0
[junit4] 2> 1576407 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[13]} 0 0
[junit4] 2> 1576408 T11019 C4390 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1576411 T11019 C4390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_l,generation=21}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_m,generation=22}
[junit4] 2> 1576412 T11019 C4390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
[junit4] 2> 1576413 T11019 C4390 oass.SolrIndexSearcher.<init> Opening Searcher@ca9a40[collection1] main
[junit4] 2> 1576414 T11019 C4390 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1576414 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ca9a40[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(6.0.0):C13)))}
[junit4] 2> 1576415 T11019 C4390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 1576422 T11019 C4390 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=6
[junit4] 2> 1576432 T11019 C4390 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=7
[junit4] 2> 1576447 T11019 C4390 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=13
[junit4] 2> 1576460 T11019 C4390 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=11
[junit4] 2> 1577368 T11019 C4390 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(50.0+89.8),1.798640735523327))&rows=1000&wt=xml} hits=2 status=0 QTime=906
[junit4] 2> 1578184 T11019 C4390 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=815
[junit4] 2> ASYNC NEW_CORE C4391 name=collection1 org.apache.solr.core.SolrCore@ee2688
[junit4] 2> 1579531 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(50.0+-89.8),1.798640735523327))&rows=1000&wt=xml} hits=2 status=0 QTime=1345
[junit4] 2> 1580838 T11019 C4391 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=1304
[junit4] 2> 1580851 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(-80.0+33.0),2.6979611032849906))&rows=1000&wt=xml} hits=2 status=0 QTime=11
[junit4] 2> 1580864 T11019 C4391 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=11
[junit4] 2> 1580871 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(BUFFER(POINT(1.0+1.0),44.96601838808318))&rows=1000&wt=xml} hits=3 status=0 QTime=6
[junit4] 2> 1580878 T11019 C4391 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=5
[junit4] 2> 1580892 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Ddistance}Intersects(ENVELOPE(-0.034898260626377464,+0.23489826062637748,+0.23489805516424955,+-0.03489805516424954))&rows=1000&wt=xml} hits=2 status=0 QTime=12
[junit4] 2> 1580906 T11019 C4391 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=12
[junit4] 2> 1580907 T11019 C4391 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1580908 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1580910 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[14]} 0 1
[junit4] 2> 1580911 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[15]} 0 1
[junit4] 2> 1580912 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[16]} 0 1
[junit4] 2> 1580913 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[17]} 0 1
[junit4] 2> 1580913 T11019 C4391 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1580916 T11019 C4391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_m,generation=22}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_n,generation=23}
[junit4] 2> 1580916 T11019 C4391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
[junit4] 2> 1580918 T11019 C4391 oass.SolrIndexSearcher.<init> Opening Searcher@158c1df[collection1] main
[junit4] 2> 1580918 T11019 C4391 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1580918 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@158c1df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(6.0.0):C4)))}
[junit4] 2> 1580919 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 1580952 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash}Intersects(BUFFER(POINT(0.0+0.0),8.993203677616636))&rows=1000&wt=xml} hits=1 status=0 QTime=32
[junit4] 2> 1580985 T11019 C4391 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=31
[junit4] 2> 1580998 T11019 C4391 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),17.986407355233272))&rows=1000&wt=xml} hits=2 status=0 QTime=11
[junit4] 2> 1581010 T11019 C4391 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=11
[junit4] 2> 1581020 T11019 C4391 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=8
[junit4] 2> 1581030 T11019 C4391 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=8
[junit4] 2> 1581048 T11019 C4391 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),26.98860423652752))&rows=1000&wt=xml} hits=3 status=0 QTime=16
[junit4] 2> 1581066 T11019 C4391 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=16
[junit4] 2> 1581084 T11019 C4391 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=16
[junit4] 2> 1581102 T11019 C4391 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=16
[junit4] 2> 1581126 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3DrecipDistance}Intersects(BUFFER(POINT(-96.789603+43.51703),0.9802592008602133))&rows=1000&wt=xml} hits=0 status=0 QTime=22
[junit4] 2> 1581149 T11019 C4391 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=22
[junit4] 2> 1581172 T11019 C4391 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=22
[junit4] 2> 1581195 T11019 C4391 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=22
[junit4] 2> 1581210 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id&fq={!field+f%3Dstqpt_geohash+score%3Dnone}Intersects(ENVELOPE(-98.15383081481679,+-95.42537518518321,+44.50628240453783,+42.52777759546217))&rows=1000&wt=xml} hits=1 status=0 QTime=14
[junit4] 2> 1581225 T11019 C4391 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=13
[junit4] 2> 1581227 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testIntersectFilter {p0=stqpt_geohash}
[junit4] 2> 1581231 T11019 oas.SolrTestCaseJ4.setUp ###Starting testSpatialConfig {p0=stqpt_geohash}
[junit4] 2> 1581232 T11019 C4391 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1581233 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1581234 T11019 C4391 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1581234 T11019 C4391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_n,generation=23}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_o,generation=24}
[junit4] 2> 1581235 T11019 C4391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
[junit4] 2> 1581235 T11019 C4391 oass.SolrIndexSearcher.<init> Opening Searcher@144d592[collection1] main
[junit4] 2> 1581236 T11019 C4391 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1581236 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@144d592[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1581237 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1581238 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testSpatialConfig {p0=stqpt_geohash}
[junit4] 2> 1581242 T11019 oas.SolrTestCaseJ4.setUp ###Starting checkResultFormat {p0=stqpt_geohash}
[junit4] 2> 1581242 T11019 C4391 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1581243 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1581244 T11019 C4391 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1581244 T11019 C4391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_o,generation=24}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_p,generation=25}
[junit4] 2> 1581245 T11019 C4391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
[junit4] 2> 1581246 T11019 C4391 oass.SolrIndexSearcher.<init> Opening Searcher@13926fb[collection1] main
[junit4] 2> 1581246 T11019 C4391 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1581246 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13926fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1581247 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1581248 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[11]} 0 0
[junit4] 2> 1581249 T11019 C4391 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1581251 T11019 C4391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_p,generation=25}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_q,generation=26}
[junit4] 2> 1581251 T11019 C4391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
[junit4] 2> 1581252 T11019 C4391 oass.SolrIndexSearcher.<init> Opening Searcher@4ff687[collection1] main
[junit4] 2> 1581253 T11019 C4391 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1581253 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ff687[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.0.0):C1)))}
[junit4] 2> 1581254 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 1581341 T11019 C4391 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&fl=id,stqpt_geohash&fq={!bbox+sfield%3Dstqpt_geohash+pt%3D89.9,-130+d%3D9}&rows=1000&wt=xml} hits=1 status=0 QTime=86
[junit4] 2> 1581343 T11019 oas.SolrTestCaseJ4.tearDown ###Ending checkResultFormat {p0=stqpt_geohash}
[junit4] 2> 1581348 T11019 oas.SolrTestCaseJ4.setUp ###Starting testBadShapeParse400 {p0=stqpt_geohash}
[junit4] 2> 1581348 T11019 C4391 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1581349 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1581350 T11019 C4391 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1581351 T11019 C4391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_q,generation=26}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_r,generation=27}
[junit4] 2> 1581351 T11019 C4391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
[junit4] 2> 1581352 T11019 C4391 oass.SolrIndexSearcher.<init> Opening Searcher@cfa999[collection1] main
[junit4] 2> 1581352 T11019 C4391 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1581352 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cfa999[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1581353 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
[junit4] 2> 1581355 T11019 C4391 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 1581356 T11019 C4391 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=2
[junit4] 2> 1581357 T11019 C4391 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 1581357 T11019 C4391 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> 1581358 T11019 C4391 oasc.SolrException.log Ignoring exception matching .
[junit4] 2> 1581359 T11019 C4391 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> 1581359 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {} 0 0
[junit4] 2> 1581361 T11019 C4391 oasc.SolrException.log Ignoring exception matching NonexistentShape
[junit4] 2> 1581361 T11019 oas.SolrTestCaseJ4.tearDown ###Ending testBadShapeParse400 {p0=stqpt_geohash}
[junit4] 2> 1581365 T11019 oas.SolrTestCaseJ4.setUp ###Starting testBadScoreParam {p0=stqpt_geohash}
[junit4] 2> 1581366 T11019 C4391 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1581367 T11019 C4391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 1581367 T11019 C4391 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1581368 T11019 C4391 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_r,generation=27}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@145ae25 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ea7e41),segFN=segments_s,generation=28}
[junit4] 2> 1581369 T11019 C4391 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
[junit4] 2> 1581369 T11019 C4391 oass.SolrIndexSearcher.<init> Opening Searcher@7a45a1[collection1] main
[junit4] 2> 1581370 T11019 C4391 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1581370 T11021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a45a1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1581371 T11019 C4391 oasup.LogUpda
[...truncated too long message...]
All Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=11022, name=Thread-3973, state=WAITING, group=TGRP-TestSolr4Spatial]
[junit4] 2> 2) Thread[id=11021, name=searcherExecutor-4338-thread-1, state=WAITING, group=TGRP-TestSolr4Spatial]
[junit4] 2> 30386 T11018 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=11022, name=Thread-3973, 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> 2) Thread[id=11021, name=searcherExecutor-4338-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> NOTE: test params are: codec=Lucene50, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=fr_CH, timezone=Asia/Dacca
[junit4] 2> NOTE: Linux 3.13.0-45-generic i386/Oracle Corporation 1.9.0-ea (32-bit)/cpus=8,threads=3,free=240933680,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [SuggesterTest, TestRandomDVFaceting, TestIBSimilarityFactory, TestStressLucene, IndexSchemaRuntimeFieldTest, BasicDistributedZkTest, DistributedFacetPivotLongTailTest, TestCloudPivotFacet, DistribJoinFromCollectionTest, TestSolrConfigHandler, DeleteReplicaTest, TestReplicationHandler, TestCharFilters, LeaderElectionTest, TestLFUCache, TestSolrXml, TestPostingsSolrHighlighter, PreAnalyzedUpdateProcessorTest, RemoteQueryErrorTest, TestCollapseQParserPlugin, SimpleFacetsTest, DocumentAnalysisRequestHandlerTest, TestDocBasedVersionConstraints, ScriptEngineTest, TestSweetSpotSimilarityFactory, TestBulkSchemaConcurrent, AnalysisAfterCoreReloadTest, BJQParserTest, TestGroupingSearch, RecoveryZkTest, TestDistributedMissingSort, TestDynamicLoading, DocValuesMultiTest, SliceStateTest, RequestHandlersTest, HdfsCollectionsAPIDistributedZkTest, TestSolrConfigHandlerConcurrent, SpatialHeatmapFacetsTest, PingRequestHandlerTest, TestCloudManagedSchema, TestHighlightDedupGrouping, HdfsDirectoryTest, UnloadDistributedZkTest, TestDocumentBuilder, TestBinaryResponseWriter, ZkCLITest, DistributedSpellCheckComponentTest, TestIndexSearcher, FieldAnalysisRequestHandlerTest, PrimUtilsTest, CursorMarkTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestCloudManagedSchemaConcurrent, TestQueryUtils, TestExceedMaxTermLength, TestRawResponseWriter, TestExactStatsCache, TestReRankQParserPlugin, DocValuesTest, SolrIndexSplitterTest, AsyncMigrateRouteKeyTest, TestBM25SimilarityFactory, TestTrackingShardHandlerFactory, TestStressReorder, DistributedSuggestComponentTest, DistributedFacetPivotLargeTest, TestExactSharedStatsCache, TestMiniSolrCloudClusterSSL, TestSchemaVersionResource, UniqFieldsUpdateProcessorFactoryTest, TestSolrConfigHandlerCloud, TestMiniSolrCloudCluster, BufferStoreTest, PeerSyncTest, TestOrdValues, TestWordDelimiterFilterFactory, PreAnalyzedFieldTest, TestSolrDynamicMBean, TestCloudSchemaless, SimpleMLTQParserTest, TestCollationField, SOLR749Test, AnalysisErrorHandlingTest, TestSurroundQueryParser, TestComplexPhraseQParserPlugin, NumericFieldsTest, OutputWriterTest, InfoHandlerTest, TestFieldTypeResource, CachingDirectoryFactoryTest, TestArbitraryIndexDir, TestSolr4Spatial2, AddSchemaFieldsUpdateProcessorFactoryTest, TestRebalanceLeaders, BadComponentTest, HdfsRecoveryZkTest, TestXIncludeConfig, TestInitQParser, DefaultValueUpdateProcessorTest, DirectSolrConnectionTest, TestInitParams, CoreAdminRequestStatusTest, TestSort, UUIDFieldTest, TestAnalyzedSuggestions, HttpPartitionTest, TestOmitPositions, CircularListTest, SampleTest, DOMUtilTest, ActionThrottleTest, TestSerializedLuceneMatchVersion, TestCopyFieldCollectionResource, FullSolrCloudDistribCmdsTest, TestFaceting, TestHashPartitioner, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, TestCoreContainer, TestSolr4Spatial]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolr4Spatial -Dtests.seed=3FA4C8399F64215A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr_CH -Dtests.timezone=Asia/Dacca -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestSolr4Spatial (suite) <<<
[junit4] > Throwable #1: java.lang.RuntimeException: SolrCore.getOpenCount()==6
[junit4] > at __randomizedtesting.SeedInfo.seed([3FA4C8399F64215A]: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=11022, name=Thread-3973, 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] > 2) Thread[id=11021, name=searcherExecutor-4338-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] > at __randomizedtesting.SeedInfo.seed([3FA4C8399F64215A]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=11022, name=Thread-3973, 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] > 2) Thread[id=11021, name=searcherExecutor-4338-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] > at __randomizedtesting.SeedInfo.seed([3FA4C8399F64215A]:0)
[junit4] Completed on J0 in 30.48s, 45 tests, 3 errors, 2 skipped <<< FAILURES!
[...truncated 626 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:191: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1351: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:958: There were test failures: 468 suites, 1868 tests, 3 suite-level errors, 836 ignored (21 assumptions)
Total time: 62 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.9.0-ea-b47 -client -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any