[JENKINS] Lucene-Solr-5.x-Windows (64bit/jdk1.8.0_40) - Build # 4455 - Failure!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4455/
Java: 64bit/jdk1.8.0_40 -XX:+UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.core.TestSolrConfigHandler

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf\params.json: 
java.nio.file.FileSystemException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf\params.json: The process 
cannot access the file because it is being used by another process.     
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf\params.json: 
java.nio.file.FileSystemException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf\params.json: The process 
cannot access the file because it is being used by another process.

   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001

        at __randomizedtesting.SeedInfo.seed([3184DDE6E629C6E6]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:294)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:200)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        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:  
org.apache.solr.search.function.TestFunctionQuery.testPseudoFieldFunctions

Error Message:
unexpected map keys [e] @ response/docs/[0]

Stack Trace:
java.lang.RuntimeException: unexpected map keys [e] @ response/docs/[0]
        at 
__randomizedtesting.SeedInfo.seed([3184DDE6E629C6E6:6FBE3F9BDABF3AAA]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:882)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:829)
        at 
org.apache.solr.search.function.TestFunctionQuery.testPseudoFieldFunctions(TestFunctionQuery.java:739)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10163 lines...]
   [junit4] Suite: org.apache.solr.search.function.TestFunctionQuery
   [junit4]   2> Creating dataDir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.function.TestFunctionQuery
 3184DDE6E629C6E6-001\init-core-data-001
   [junit4]   2> 1211063 T6253 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (false)
   [junit4]   2> 1211065 T6253 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 1211065 T6253 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1211066 T6253 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1211067 T6253 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1211099 T6253 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1211102 T6253 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.1.0
   [junit4]   2> 1211105 T6253 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig-functionquery.xml
   [junit4]   2> 1211106 T6253 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema11.xml
   [junit4]   2> 1211109 T6253 oass.IndexSchema.readSchema [null] Schema 
name=example
   [junit4]   2> 1211133 T6253 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1211135 T6253 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1211140 T6253 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1211140 T6253 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr
   [junit4]   2> 1211140 T6253 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\'
   [junit4]   2> 1211164 T6253 oasc.CoreContainer.<init> New CoreContainer 
2001584760
   [junit4]   2> 1211164 T6253 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\]
   [junit4]   2> 1211164 T6253 oasc.CoreContainer.load loading shared library: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib
   [junit4]   2> 1211165 T6253 oasc.SolrResourceLoader.addToClassLoader WARN 
Can't find (or read) directory to add to classloader: lib (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib).
   [junit4]   2> 1211172 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 600000
   [junit4]   2> 1211172 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: null
   [junit4]   2> 1211172 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 60000
   [junit4]   2> 1211172 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1211172 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 1211172 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1211173 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1211173 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1211173 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1211173 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1211173 T6253 oashc.HttpShardHandlerFactory.getParameter 
Setting useRetries to: false
   [junit4]   2> 1211173 T6253 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1211174 T6253 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1211174 T6253 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1211174 T6253 oasc.CoreContainer.load Node Name: testNode
   [junit4]   2> 1211176 T6253 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: 
{name=collection1, config=solrconfig-functionquery.xml, transient=false, 
schema=schema11.xml, loadOnStartup=true, instanceDir=collection1, 
collection=collection1, 
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.function.TestFunctionQuery
 3184DDE6E629C6E6-001\init-core-data-001, shard=shard1}
   [junit4]   2> 1211177 T6254 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1211177 T6254 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1211177 T6254 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1211193 T6254 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1211197 T6254 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.1.0
   [junit4]   2> 1211200 T6254 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig-functionquery.xml
   [junit4]   2> 1211201 T6254 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema11.xml
   [junit4]   2> 1211204 T6254 oass.IndexSchema.readSchema [collection1] Schema 
name=example
   [junit4]   2> 1211224 T6254 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1211224 T6254 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1211228 T6254 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1211228 T6254 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1211228 T6254 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.function.TestFunctionQuery
 3184DDE6E629C6E6-001\init-core-data-001\
   [junit4]   2> 1211228 T6254 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1211229 T6254 oasc.CachingDirectoryFactory.get return new 
directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.function.TestFunctionQuery
 3184DDE6E629C6E6-001\init-core-data-001\
   [junit4]   2> 1211229 T6254 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.function.TestFunctionQuery
 3184DDE6E629C6E6-001\init-core-data-001\index/
   [junit4]   2> 1211229 T6254 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.function.TestFunctionQuery
 3184DDE6E629C6E6-001\init-core-data-001\index' doesn't exist. Creating new 
index...
   [junit4]   2> 1211229 T6254 oasc.CachingDirectoryFactory.get return new 
directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.function.TestFunctionQuery
 3184DDE6E629C6E6-001\init-core-data-001\index
   [junit4]   2> 1211229 T6254 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=22.0224609375, 
floorSegmentMB=1.6796875, forceMergeDeletesPctAllowed=10.617883698972495, 
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8309502919559937
   [junit4]   2> 1211230 T6254 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_1,generation=1}
   [junit4]   2> 1211230 T6254 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1211233 T6254 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1211235 T6254 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1211236 T6254 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1211237 T6254 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1211238 T6254 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1211239 T6254 oasc.RequestHandlers.initHandlersFromConfig 
Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/reloadCache,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1211240 T6254 oasc.SolrCore.initStatsCache Using default 
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1211240 T6254 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1211240 T6254 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1211241 T6254 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=29, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=93.4052734375, 
floorSegmentMB=0.615234375, forceMergeDeletesPctAllowed=11.450312528890642, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.12977411737198957
   [junit4]   2> 1211241 T6254 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_1,generation=1}
   [junit4]   2> 1211241 T6254 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1211241 T6254 oass.SolrIndexSearcher.<init> Opening 
Searcher@6d0ab866[collection1] main
   [junit4]   2> 1211242 T6254 oasr.ManagedResourceStorage.newStorageIO WARN 
Cannot write to config directory 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1211242 T6254 oasr.RestManager.init Initializing RestManager 
with initArgs: {}
   [junit4]   2> 1211242 T6254 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1211242 T6254 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1211242 T6254 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1211243 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6d0ab866[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1211243 T6254 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1211245 T6253 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 1211247 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testBooleanFunctions
   [junit4]   2> ASYNC  NEW_CORE C810 name=collection1 
org.apache.solr.core.SolrCore@2efc1bd
   [junit4]   2> 1211248 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 1
   [junit4]   2> 1211249 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1211250 T6253 C810 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211252 T6253 C810 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_2,generation=2}
   [junit4]   2> 1211252 T6253 C810 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 2
   [junit4]   2> 1211254 T6253 C810 oass.SolrIndexSearcher.<init> Opening 
Searcher@504b0000[collection1] main
   [junit4]   2> 1211254 T6253 C810 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211254 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@504b0000[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):C2)))}
   [junit4]   2> 1211254 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1211256 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:1&fl=a:testfunc(1)&wt=xml} hits=1 status=0 
QTime=1 
   [junit4]   2> 1211260 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=t:true(),f:false(),tt:{!func}true,ff:{!func}false&wt=xml} 
hits=1 status=0 QTime=3 
   [junit4]   2> 1211261 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q1=text:hello&q=id:1&q2=text:there&fl=t:exists(query($q1)),f:exists(query($q2))&wt=xml}
 hits=1 status=0 QTime=1 
   [junit4]   2> 1211262 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=a1:if(true,'A','B')&fl=b1:if(false,'A',testfunc('B'))&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1211265 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=t1:and(testfunc(true),true)&fl=f1:and(true,false)&fl=f2:and(false,true)&fl=f3:and(false,false)&wt=xml}
 hits=1 status=0 QTime=3 
   [junit4]   2> 1211266 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=t1:or(testfunc(true),true)&fl=t2:or(true,false)&fl=t3:or(false,true)&fl=f1:or(false,false)&wt=xml}
 hits=1 status=0 QTime=1 
   [junit4]   2> 1211266 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=f1:xor(testfunc(true),true)&fl=t1:xor(true,false)&fl=t2:xor(false,true)&fl=f2:xor(false,false)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211267 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=t:not(testfunc(false)),f:not(true)&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211272 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=x:def(id,testfunc(123.0)),+y:def(foo_f,234.0)&wt=xml} hits=1 
status=0 QTime=4 
   [junit4]   2> 1211273 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=x:def(foo_s,'Q'),+y:def(missing_s,'W')&wt=xml} hits=1 
status=0 QTime=0 
   [junit4]   2> 1211274 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=a:not(0),+b:not(1),+c:not(0.0),+d:not(1.1),+e:not('A')&wt=xml}
 hits=1 status=0 QTime=1 
   [junit4]   2> 1211274 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testBooleanFunctions
   [junit4]   2> 1211276 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testFieldValueSourceParser
   [junit4]   2> 1211276 T6253 C810 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1211276 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1211277 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 0
   [junit4]   2> 1211278 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 0
   [junit4]   2> 1211278 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1211279 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[0.0]} 0 0
   [junit4]   2> 1211279 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1211280 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1211280 T6253 C810 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211282 T6253 C810 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_3,generation=3}
   [junit4]   2> 1211282 T6253 C810 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 3
   [junit4]   2> 1211283 T6253 C810 oass.SolrIndexSearcher.<init> Opening 
Searcher@67704006[collection1] main
   [junit4]   2> 1211283 T6253 C810 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211284 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@67704006[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.1.0):C6/2:delGen=1)))}
   [junit4]   2> 1211284 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1211285 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[25.0]} 0 1
   [junit4]   2> 1211285 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5.0]} 0 0
   [junit4]   2> 1211286 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[77.0]} 0 1
   [junit4]   2> 1211286 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1.0]} 0 0
   [junit4]   2> 1211287 T6253 C810 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211289 T6253 C810 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_4,generation=4}
   [junit4]   2> 1211289 T6253 C810 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 4
   [junit4]   2> 1211290 T6253 C810 oass.SolrIndexSearcher.<init> Opening 
Searcher@51e929a5[collection1] main
   [junit4]   2> 1211290 T6253 C810 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211290 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@51e929a5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.1.0):C6/2:delGen=1)
 Uninverting(_2(5.1.0):C4)))}
   [junit4]   2> 1211290 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 1211292 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}field("CoMpleX+\"+fieldName+_f")&indent=on&fl=*,score&rows=100&wt=xml}
 hits=8 status=0 QTime=1 
   [junit4]   2> 1211294 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(field("CoMpleX+\"+fieldName+_f"))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=8 status=0 QTime=0 
   [junit4]   2> 1211296 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}log(field("CoMpleX+\"+fieldName+_f"))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=8 status=0 QTime=1 
   [junit4]   2> 1211296 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testFieldValueSourceParser
   [junit4]   2> 1211299 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testSortByFunc
   [junit4]   2> 1211300 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 1
   [junit4]   2> 1211300 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1211301 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 1
   [junit4]   2> 1211301 T6253 C810 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211304 T6253 C810 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_5,generation=5}
   [junit4]   2> 1211305 T6253 C810 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 5
   [junit4]   2> 1211305 T6253 C810 oass.SolrIndexSearcher.<init> Opening 
Searcher@3a79d955[collection1] main
   [junit4]   2> 1211306 T6253 C810 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211307 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3a79d955[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.1.0):C6/2:delGen=1)
 Uninverting(_2(5.1.0):C4/1:delGen=1) Uninverting(_3(5.1.0):C3)))}
   [junit4]   2> 1211307 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 1211308 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort=add(x_i,x_i)+desc&wt=xml} hits=3 status=0 
QTime=0 
   [junit4]   2> 1211310 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&x=add(x_i,x_i)&sort=const_s+asc,+$x+asc&wt=xml} 
hits=3 status=0 QTime=1 
   [junit4]   2> 1211310 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&x=5&y=add(x_i,x_i)&sort=$x+asc,+const_s+asc,+$y+desc&wt=xml}
 hits=3 status=0 QTime=0 
   [junit4]   2> 1211311 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&const=50&fl=x_i&sort=add(+10+,+10+)+asc,+const_s+asc,+add(x_i+,+$const)+desc&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 1211312 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort=const_s+asc,+{!key%3Dfoo}add(x_i,x_i)+desc&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 1211312 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort={!key%3Dfoo}add(x_i,x_i)+desc,+const_s+asc&wt=xml}
 hits=3 status=0 QTime=0 
   [junit4]   2> 1211313 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort={!key%3Dbar}add(10,20)+asc,+const_s+asc,+{!key%3Dfoo}add(x_i,x_i)+desc&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 1211314 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort={!key%3Dbar+v%3D$s1}+asc,+{!key%3Dfoo+v%3D$s2}+desc&wt=xml&s1=add(3,4)&s2=add(x_i,5)}
 hits=3 status=0 QTime=1 
   [junit4]   2> 1211314 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort={!key%3Dbar+v%3D$s1}asc,const_s+asc,{!key%3Dfoo+v%3D$s2}desc&wt=xml&s1=add(3,4)&s2=add(x_i,5)}
 hits=3 status=0 QTime=0 
   [junit4]   2> 1211315 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:[1+TO+3]&fl=x_i&sort=1_s+asc&wt=xml} hits=3 
status=0 QTime=0 
   [junit4]   2> 1211316 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:[1+TO+3]&fl=x_i&sort=x:x_i+desc&wt=xml} 
hits=3 status=0 QTime=1 
   [junit4]   2> 1211316 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:[1+TO+3]&fl=x_i&sort=1-1_s+asc&wt=xml} 
hits=3 status=0 QTime=0 
   [junit4]   2> 1211317 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort=[]_s+asc,+{!key%3Dfoo}add(x_i,x_i)+desc&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 1211318 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:[1+TO+3]&fl=x_i&sort={!lucene+v%3D'id:3'}desc,+{!key%3Dfoo}add(x_i,x_i)+asc&wt=xml}
 hits=3 status=0 QTime=1 
   [junit4]   2> 1211318 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testSortByFunc
   [junit4]   2> 1211320 T6253 oas.SolrTestCaseJ4.setUp ###Starting testFuncs
   [junit4]   2> 1211321 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 1
   [junit4]   2> 1211321 T6253 C810 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211324 T6253 C810 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_5,generation=5}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_6,generation=6}
   [junit4]   2> 1211325 T6253 C810 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 6
   [junit4]   2> 1211325 T6253 C810 oass.SolrIndexSearcher.<init> Opening 
Searcher@69f23e3e[collection1] main
   [junit4]   2> 1211325 T6253 C810 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211326 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@69f23e3e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.1.0):C6/2:delGen=1)
 Uninverting(_2(5.1.0):C4/1:delGen=1) Uninverting(_3(5.1.0):C3/1:delGen=1) 
Uninverting(_4(5.1.0):C1)))}
   [junit4]   2> 1211326 T6253 C810 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1211327 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=1.0&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211328 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=e()&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1211329 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=pi()&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211329 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=add(2,3)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211330 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=mul(2,3)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211331 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=rad(45)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211331 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=deg(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1211332 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=sqrt(9)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1211333 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=cbrt(8)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211334 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=max(0,1)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211334 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=max(10,3,8,7,5,4)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 
status=0 QTime=0 
   [junit4]   2> 1211335 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=min(0,1)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=1 
   [junit4]   2> 1211336 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=min(10,3,8,7,5,4)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 
status=0 QTime=1 
   [junit4]   2> 1211336 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=log(100)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211338 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=ln(3)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=2 
   [junit4]   2> 1211338 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=exp(1)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1211339 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=sin(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211340 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=cos(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211340 T6253 C810 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=tan(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C811 name=collection1 
org.apache.solr.core.SolrCore@2efc1bd
   [junit4]   2> 1211379 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=asin(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211380 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=acos(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211381 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=atan(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211382 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=sinh(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=1 
   [junit4]   2> 1211382 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=cosh(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211383 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=tanh(.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=1 
   [junit4]   2> 1211402 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=ceil(2.3)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=19 
   [junit4]   2> 1211403 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=floor(2.3)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211404 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=rint(2.3)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211408 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=pow(2,0.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=3 
   [junit4]   2> 1211409 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=hypot(3,4)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=1 
   [junit4]   2> 1211409 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=atan2(.25,.5)&defType=func&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211409 T6253 oas.SolrTestCaseJ4.tearDown ###Ending testFuncs
   [junit4]   2> 1211417 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testMissingFieldFunctionBehavior
   [junit4]   2> 1211417 T6253 C811 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1211419 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
   [junit4]   2> 1211419 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1211420 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211430 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_6,generation=6}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_7,generation=7}
   [junit4]   2> 1211431 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 7
   [junit4]   2> 1211432 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@4f054af[collection1] main
   [junit4]   2> 1211432 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211432 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4f054af[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(5.1.0):C1)))}
   [junit4]   2> 1211433 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 1211433 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____s,42,-99)&fl=noval_def:def(no__vals____s,-99)&fl=noval_not:not(no__vals____s)&fl=noval_exists:exists(no__vals____s)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211437 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____b,42,-99)&fl=noval_def:def(no__vals____b,-99)&fl=noval_not:not(no__vals____b)&fl=noval_exists:exists(no__vals____b)&wt=xml}
 hits=1 status=0 QTime=3 
   [junit4]   2> 1211440 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____dt,42,-99)&fl=noval_def:def(no__vals____dt,-99)&fl=noval_not:not(no__vals____dt)&fl=noval_exists:exists(no__vals____dt)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211441 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____tdt,42,-99)&fl=noval_def:def(no__vals____tdt,-99)&fl=noval_not:not(no__vals____tdt)&fl=noval_exists:exists(no__vals____tdt)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211442 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____i,42,-99)&fl=noval_def:def(no__vals____i,-99)&fl=noval_not:not(no__vals____i)&fl=noval_exists:exists(no__vals____i)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211443 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____l,42,-99)&fl=noval_def:def(no__vals____l,-99)&fl=noval_not:not(no__vals____l)&fl=noval_exists:exists(no__vals____l)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211443 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____f,42,-99)&fl=noval_def:def(no__vals____f,-99)&fl=noval_not:not(no__vals____f)&fl=noval_exists:exists(no__vals____f)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211443 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____d,42,-99)&fl=noval_def:def(no__vals____d,-99)&fl=noval_not:not(no__vals____d)&fl=noval_exists:exists(no__vals____d)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211444 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____ti,42,-99)&fl=noval_def:def(no__vals____ti,-99)&fl=noval_not:not(no__vals____ti)&fl=noval_exists:exists(no__vals____ti)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211450 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____tl,42,-99)&fl=noval_def:def(no__vals____tl,-99)&fl=noval_not:not(no__vals____tl)&fl=noval_exists:exists(no__vals____tl)&wt=xml}
 hits=1 status=0 QTime=5 
   [junit4]   2> 1211451 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____tf,42,-99)&fl=noval_def:def(no__vals____tf,-99)&fl=noval_not:not(no__vals____tf)&fl=noval_exists:exists(no__vals____tf)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211452 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=noval_if:if(no__vals____td,42,-99)&fl=noval_def:def(no__vals____td,-99)&fl=noval_not:not(no__vals____td)&fl=noval_exists:exists(no__vals____td)&wt=xml}
 hits=1 status=0 QTime=1 
   [junit4]   2> 1211452 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testMissingFieldFunctionBehavior
   [junit4]   2> 1211455 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testExternalFileFieldStringKeys
   [junit4]   2> 1211455 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[991]} 0 0
   [junit4]   2> 1211456 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[992]} 0 0
   [junit4]   2> 1211456 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[993]} 0 0
   [junit4]   2> 1211457 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211459 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_7,generation=7}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_8,generation=8}
   [junit4]   2> 1211459 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 8
   [junit4]   2> 1211460 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@197909ba[collection1] main
   [junit4]   2> 1211460 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211460 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@197909ba[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(5.1.0):C1)
 Uninverting(_6(5.1.0):C3)))}
   [junit4]   2> 1211460 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 1211477 T6253 C811 oassf.FileFloatSource.getFloats Loaded 
external value source external_foo_extfs
   [junit4]   2> 1211478 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_extfs&indent=on&fl=*,score&rows=100&wt=xml} hits=4 
status=0 QTime=16 
   [junit4]   2> 1211479 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testExternalFileFieldStringKeys
   [junit4]   2> 1211481 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testPseudoFieldFunctions
   [junit4]   2> 1211482 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 1
   [junit4]   2> 1211483 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1211483 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211484 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_8,generation=8}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_9,generation=9}
   [junit4]   2> 1211485 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 9
   [junit4]   2> 1211486 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@9dcccdf[collection1] main
   [junit4]   2> 1211486 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@9dcccdf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)
 Uninverting(_7(5.1.0):C2)))}
   [junit4]   2> 1211486 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211487 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1211487 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=a:1,b:2.0,c:'X',d:{!func}foo_s,e:{!func}bar_s&wt=xml} hits=1 
status=0 QTime=0 
   [junit4]   2> 1211490 T6253 oas.SolrTestCaseJ4.assertJQ ERROR query failed 
JSON validation. error=unexpected map keys [e] @ response/docs/[0]
   [junit4]   2>         expected =/response/docs/[0]=={'a':1, 
'b':2.0,'c':'X','d':'A'}
   [junit4]   2>         response = {
   [junit4]   2>          "responseHeader":{
   [junit4]   2>            "status":0,
   [junit4]   2>            "QTime":0},
   [junit4]   2>          "response":{"numFound":1,"start":0,"docs":[
   [junit4]   2>              {
   [junit4]   2>                "a":1,
   [junit4]   2>                "b":2.0,
   [junit4]   2>                "c":"X",
   [junit4]   2>                "d":"A",
   [junit4]   2>                "e":null}]
   [junit4]   2>          }}
   [junit4]   2>        
   [junit4]   2>         request = 
q=id:1&fl=a:1,b:2.0,c:'X',d:{!func}foo_s,e:{!func}bar_s&wt=xml
   [junit4]   2> 1211491 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testPseudoFieldFunctions
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestFunctionQuery 
-Dtests.method=testPseudoFieldFunctions -Dtests.seed=3184DDE6E629C6E6 
-Dtests.slow=true -Dtests.locale=en_US -Dtests.timezone=Pacific/Enderbury 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.02s J0 | TestFunctionQuery.testPseudoFieldFunctions <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: unexpected map keys 
[e] @ response/docs/[0]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3184DDE6E629C6E6:6FBE3F9BDABF3AAA]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:882)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:829)
   [junit4]    >        at 
org.apache.solr.search.function.TestFunctionQuery.testPseudoFieldFunctions(TestFunctionQuery.java:739)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1211498 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testFunctions
   [junit4]   2> 1211498 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 0
   [junit4]   2> 1211499 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1211500 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[0.0]} 0 0
   [junit4]   2> 1211501 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[0.0]} 0 0
   [junit4]   2> 1211502 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1211502 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[25.0]} 0 0
   [junit4]   2> 1211503 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5.0]} 0 0
   [junit4]   2> 1211503 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211506 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_9,generation=9}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_a,generation=10}
   [junit4]   2> 1211506 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 10
   [junit4]   2> 1211507 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@73bb2a76[collection1] main
   [junit4]   2> 1211507 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211507 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@73bb2a76[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)
 Uninverting(_7(5.1.0):C2) Uninverting(_8(5.1.0):C7/1:delGen=1)))}
   [junit4]   2> 1211508 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1211508 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[88.0]} 0 0
   [junit4]   2> 1211509 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211511 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_a,generation=10}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_b,generation=11}
   [junit4]   2> 1211512 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 11
   [junit4]   2> 1211512 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@1f7cfa54[collection1] main
   [junit4]   2> 1211513 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211513 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1f7cfa54[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)
 Uninverting(_7(5.1.0):C2) Uninverting(_8(5.1.0):C7/1:delGen=1) 
Uninverting(_9(5.1.0):C1)))}
   [junit4]   2> 1211513 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1211514 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_f&indent=on&fl=*,score&rows=100&wt=xml} hits=12 status=0 
QTime=0 
   [junit4]   2> 1211515 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211516 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}-1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211517 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211518 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_f,foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211518 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_f,foo_f,5)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211519 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sub(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211520 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}product(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211520 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}product(foo_f,-2,-4)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211521 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}log(foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211523 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211524 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}abs(foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211524 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}pow(foo_f,foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211525 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}pow(foo_f,0.5)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211526 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211526 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211527 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(abs(foo_f))&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211528 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(sum(29,foo_f))&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211532 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_f,0,0,500)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=4 
   [junit4]   2> 1211533 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_f,-4,5,500)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211535 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_f,0,0,sum(foo_f,500))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211536 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_f,0,0,sum(foo_f,500),sum(foo_f,1))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=1 
   [junit4]   2> 1211536 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_f,-4,5,sum(foo_f,1))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211544 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}scale(foo_f,-1,1)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=6 
   [junit4]   2> 1211546 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}scale(foo_f,-10,1000)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211548 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}scale(log(foo_f),-1000,1000)&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=1 
   [junit4]   2> 1211550 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}nvl(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211551 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}nvl(sum(0,foo_f),1)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=1 
   [junit4]   2> 1211552 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}query({!func+v%3Dfoo_f})&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=1 
   [junit4]   2> 1211553 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}query({!lucene+v%3D'foo_f:[*+TO+*]'},8)&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211554 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(query({!func+v%3Dfoo_f},7.1),query({!func+v%3Dfoo_f}))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211555 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={vv=foo_f&q={!func}query({!func+v%3D$vv})&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211555 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={vv={!func}foo_f&q={!func}query($vv)&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211556 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(query($v1,5),query($v1,7))&indent=on&fl=*,score&v1=foo_f:[*+TO+*]&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211557 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 0
   [junit4]   2> 1211557 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1211558 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[0.0]} 0 0
   [junit4]   2> 1211558 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1211559 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[25.0]} 0 0
   [junit4]   2> 1211559 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211562 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_b,generation=11}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_c,generation=12}
   [junit4]   2> 1211563 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 12
   [junit4]   2> 1211563 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@455e945f[collection1] main
   [junit4]   2> 1211564 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211564 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@455e945f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)
 Uninverting(_7(5.1.0):C2) Uninverting(_8(5.1.0):C7/6:delGen=2) 
Uninverting(_9(5.1.0):C1) Uninverting(_a(5.1.0):C5)))}
   [junit4]   2> 1211565 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 1211568 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5.0]} 0 0
   [junit4]   2> 1211569 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211571 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_c,generation=12}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_d,generation=13}
   [junit4]   2> 1211571 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 13
   [junit4]   2> 1211572 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@5e4ea9ee[collection1] main
   [junit4]   2> 1211572 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211572 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5e4ea9ee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)
 Uninverting(_7(5.1.0):C2) Uninverting(_9(5.1.0):C1) Uninverting(_a(5.1.0):C5) 
Uninverting(_b(5.1.0):C1)))}
   [junit4]   2> 1211572 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1211573 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[88.0]} 0 0
   [junit4]   2> 1211573 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211576 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_d,generation=13}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_e,generation=14}
   [junit4]   2> 1211576 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 14
   [junit4]   2> 1211578 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@757fd6d7[collection1] main
   [junit4]   2> 1211578 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211579 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@757fd6d7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)
 Uninverting(_7(5.1.0):C2) Uninverting(_a(5.1.0):C5) Uninverting(_b(5.1.0):C1) 
Uninverting(_c(5.1.0):C1)))}
   [junit4]   2> 1211579 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 1211580 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_tf&indent=on&fl=*,score&rows=100&wt=xml} hits=12 status=0 
QTime=1 
   [junit4]   2> 1211580 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211581 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}-1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211582 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211582 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_tf,foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211583 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_tf,foo_tf,5)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211584 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sub(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211584 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}product(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211585 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}product(foo_tf,-2,-4)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211585 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}log(foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211587 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211588 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}abs(foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211589 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}pow(foo_tf,foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=1 
   [junit4]   2> 1211589 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}pow(foo_tf,0.5)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211591 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211592 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=1 
   [junit4]   2> 1211592 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(abs(foo_tf))&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211593 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(sum(29,foo_tf))&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211594 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_tf,0,0,500)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=1 
   [junit4]   2> 1211596 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_tf,-4,5,500)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211597 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_tf,0,0,sum(foo_tf,500))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211599 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_tf,0,0,sum(foo_tf,500),sum(foo_tf,1))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211601 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}map(foo_tf,-4,5,sum(foo_tf,1))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=1 
   [junit4]   2> 1211602 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}scale(foo_tf,-1,1)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211603 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}scale(foo_tf,-10,1000)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211605 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}scale(log(foo_tf),-1000,1000)&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=1 
   [junit4]   2> 1211605 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}nvl(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=12 
status=0 QTime=0 
   [junit4]   2> 1211606 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}nvl(sum(0,foo_tf),1)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=12 status=0 QTime=0 
   [junit4]   2> 1211607 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}query({!func+v%3Dfoo_tf})&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211608 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}query({!lucene+v%3D'foo_tf:[*+TO+*]'},8)&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211609 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(query({!func+v%3Dfoo_tf},7.1),query({!func+v%3Dfoo_tf}))&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211609 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={vv=foo_tf&q={!func}query({!func+v%3D$vv})&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211611 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={vv={!func}foo_tf&q={!func}query($vv)&indent=on&fl=*,score&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211612 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(query($v1,5),query($v1,7))&indent=on&fl=*,score&v1=foo_tf:[*+TO+*]&rows=100&wt=xml}
 hits=12 status=0 QTime=0 
   [junit4]   2> 1211613 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testFunctions
   [junit4]   2> 1211618 T6253 oas.SolrTestCaseJ4.setUp ###Starting 
testStrDistance
   [junit4]   2> 1211618 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1211619 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211622 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_e,generation=14}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_f,generation=15}
   [junit4]   2> 1211622 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 15
   [junit4]   2> 1211623 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@74e38b94[collection1] main
   [junit4]   2> 1211623 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211623 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@74e38b94[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.1.0):C3)
 Uninverting(_7(5.1.0):C2/1:delGen=1) Uninverting(_a(5.1.0):C5) 
Uninverting(_b(5.1.0):C1) Uninverting(_c(5.1.0):C1) Uninverting(_d(5.1.0):C1)))}
   [junit4]   2> 1211624 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1211625 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}strdist(x_s,+'foit',+edit)&fl=*,score&fq=id:1&wt=xml} hits=1 
status=0 QTime=1 
   [junit4]   2> 1211626 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}strdist(x_s,+'foit',+jw)&fl=*,score&fq=id:1&wt=xml} hits=1 
status=0 QTime=1 
   [junit4]   2> 1211629 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}strdist(x_s,+'foit',+ngram,+2)&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=3 
   [junit4]   2> 1211630 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=id:1&fl=good:strdist(x_s,+'toil',+edit)&fl=bad1:strdist(missing1_s,+missing2_s,+edit)&fl=bad2:strdist(missing1_s,+'something',+edit)&fl=bad3:strdist(missing1_s,+x_s,+edit)&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211631 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}strdist(missing1_s,+missing2_s,+edit)&fl=score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211631 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}strdist(missing1_s,+x_s,+edit)&fl=score&fq=id:1&wt=xml} hits=1 
status=0 QTime=0 
   [junit4]   2> 1211632 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}strdist(missing1_s,+'const',+edit)&fl=score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211633 T6253 oas.SolrTestCaseJ4.tearDown ###Ending 
testStrDistance
   [junit4]   2> 1211636 T6253 oas.SolrTestCaseJ4.setUp ###Starting testGeneral
   [junit4]   2> 1211636 T6253 C811 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1211636 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1211637 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1211637 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1211638 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211640 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_f,generation=15}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_g,generation=16}
   [junit4]   2> 1211640 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 16
   [junit4]   2> 1211641 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@3588f587[collection1] main
   [junit4]   2> 1211641 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211642 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3588f587[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(5.1.0):C2)))}
   [junit4]   2> 1211642 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1211643 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 1
   [junit4]   2> 1211643 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1211643 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211645 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_g,generation=16}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_h,generation=17}
   [junit4]   2> 1211645 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 17
   [junit4]   2> 1211645 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@3c6017d6[collection1] main
   [junit4]   2> 1211646 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211646 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3c6017d6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(5.1.0):C2)
 Uninverting(_f(5.1.0):C2)))}
   [junit4]   2> 1211647 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1211647 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1211648 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1211648 T6253 C811 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1211649 T6253 C811 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_h,generation=17}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@34d30d40 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@752fc21e),segFN=segments_i,generation=18}
   [junit4]   2> 1211650 T6253 C811 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 18
   [junit4]   2> 1211651 T6253 C811 oass.SolrIndexSearcher.<init> Opening 
Searcher@100439c0[collection1] main
   [junit4]   2> 1211651 T6253 C811 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1211651 T6255 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@100439c0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(5.1.0):C2)
 Uninverting(_f(5.1.0):C2) Uninverting(_g(5.1.0):C2)))}
   [junit4]   2> 1211651 T6253 C811 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 1211654 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}numdocs()&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=3 
   [junit4]   2> 1211655 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}maxdoc()&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211658 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}docfreq(a_t,cow)&fl=*,score&fq=id:6&wt=xml} hits=1 status=0 
QTime=3 
   [junit4]   2> 1211659 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}docfreq('a_t','cow')&fl=*,score&fq=id:6&wt=xml} hits=1 
status=0 QTime=1 
   [junit4]   2> 1211659 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}docfreq($field,$value)&field=a_t&fl=*,score&fq=id:6&value=cow&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1211665 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}termfreq(a_t,cow)&fl=*,score&fq=id:6&wt=xml} hits=1 status=0 
QTime=6 
   [junit4]   2> 1211666 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}termfreq(nofield_t,cow)&fl=*,score&fq=id:6&wt=xml} hits=1 
status=0 QTime=0 
   [junit4]   2> 1211667 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}docfreq(nofield_t,cow)&fl=*,score&fq=id:6&wt=xml} hits=1 
status=0 QTime=1 
   [junit4]   2> 1211670 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}idf(nofield_t,cow)&fl=*,score&fq=id:6&wt=xml} hits=1 status=0 
QTime=3 
   [junit4]   2> 1211676 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}tf(nofield_t,cow)&fl=*,score&fq=id:6&wt=xml} hits=1 status=0 
QTime=6 
   [junit4]   2> 1211677 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}idf(a_t,cow)&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211677 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}tf(a_t,cow)&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1211680 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}norm(a_t)&fl=*,score&fq=id:2&wt=xml} 
hits=1 status=0 QTime=2 
   [junit4]   2> 1211690 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}ord(id)&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=10 
   [junit4]   2> 1211692 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}top(ord(id))&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=2 
   [junit4]   2> 1211694 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}rord(id)&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=2 
   [junit4]   2> 1211695 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}top(rord(id))&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211696 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}ms(a_tdt,b_tdt)&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=1 
   [junit4]   2> 1211696 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}ms(b_tdt,a_tdt)&fl=*,score&fq=id:1&wt=xml} hits=1 status=0 
QTime=0 
   [junit4]   2> 1211697 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}ms(2009-08-31T12:10:10.125Z,2009-08-31T12:10:10.124Z)&fl=*,score&fq=id:1&wt=xml}
 hits=1 status=0 QTime=1 
   [junit4]   2> 1211699 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}ms(2009-08-31T12:10:10.124Z,a_tdt)&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1211699 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}ms(2009-08-31T12:10:10.125Z,b_tdt)&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1211700 T6253 C811 oasc.SolrCore.execute [collection1] 
webapp=null path=n

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

ore.TestSolrConfigHandler 
3184DDE6E629C6E6-001\tempDir-012\cores\core\data\index 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-012\cores\core\data\index;done=false>>]
   [junit4]   2> 1955242 T10328 oasc.CachingDirectoryFactory.close Closing 
directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-012\cores\core\data\index
   [junit4]   2> 1955242 T10328 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1955599 T10328 oas.SolrTestCaseJ4.tearDown ###Ending 
testReqParams
   [junit4]   2> 1955600 T10328 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): {}, 
docValues:{}, sim=DefaultSimilarity, locale=da_DK, timezone=US/Arizona
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_40 
(64-bit)/cpus=3,threads=1,free=360072840,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [RollingRestartTest, 
LeaderFailoverAfterPartitionTest, TestTrackingShardHandlerFactory, 
TestFreeTextSuggestions, DistributedDebugComponentTest, 
DefaultValueUpdateProcessorTest, TestShardHandlerFactory, DistanceFunctionTest, 
DeleteShardTest, UUIDFieldTest, RecoveryZkTest, BlockCacheTest, 
DocumentAnalysisRequestHandlerTest, SuggesterTest, TestCSVResponseWriter, 
ZkCLITest, OverseerStatusTest, StressHdfsTest, TestReloadAndDeleteDocs, 
PreAnalyzedFieldTest, TestReplicaProperties, TestNRTOpen, 
AlternateDirectoryTest, SolrTestCaseJ4Test, TestBinaryResponseWriter, 
DocValuesMissingTest, SampleTest, TestFieldResource, ActionThrottleTest, 
TestConfigSets, TestCloudManagedSchema, TestSolrXml, TestExactSharedStatsCache, 
HdfsUnloadDistributedZkTest, SpatialHeatmapFacetsTest, BadComponentTest, 
TestGroupingSearch, TestCSVLoader, TestShortCircuitedRequests, TestWriterPerf, 
TestComplexPhraseQParserPlugin, TestNoOpRegenerator, TestElisionMultitermQuery, 
AssignTest, TestIndexSearcher, SystemInfoHandlerTest, 
SolrCoreCheckLockOnStartupTest, TestSolrQueryParserResource, 
SimpleCollectionCreateDeleteTest, DistribDocExpirationUpdateProcessorTest, 
DistributedSpellCheckComponentTest, TestQueryUtils, TestFileDictionaryLookup, 
TestCollationField, TestExceedMaxTermLength, AtomicUpdatesTest, 
HighlighterMaxOffsetTest, TestReplicationHandler, CoreAdminHandlerTest, 
SolrCoreTest, SimpleFacetsTest, TestStressReorder, TestAtomicUpdateErrorCases, 
TestQueryTypes, DistributedSuggestComponentTest, XmlUpdateRequestHandlerTest, 
TestIndexingPerformance, HdfsRecoveryZkTest, TestDistributedSearch, 
TestPivotHelperCode, TestCharFilters, TestExactStatsCache, 
RemoteQueryErrorTest, TestBinaryField, SOLR749Test, 
OverriddenZkACLAndCredentialsProvidersTest, SyncSliceTest, SortByFunctionTest, 
TestManagedSchema, PreAnalyzedUpdateProcessorTest, TestManagedResourceStorage, 
TestManagedSynonymFilterFactory, SolrPluginUtilsTest, 
TestDistributedMissingSort, TestJmxIntegration, TestIntervalFaceting, 
TestWordDelimiterFilterFactory, TestManagedSchemaFieldResource, 
TestJettySolrRunner, DistributedExpandComponentTest, TestNamedUpdateProcessors, 
CoreMergeIndexesAdminHandlerTest, CacheHeaderTest, 
TestSolrConfigHandlerConcurrent, TestMaxScoreQueryParser, DistanceUnitsTest, 
PathHierarchyTokenizerFactoryTest, TestLRUCache, DirectUpdateHandlerTest, 
HdfsSyncSliceTest, TestPerFieldSimilarity, TestJmxMonitoredMap, 
TestArbitraryIndexDir, TestRawResponseWriter, TestFieldSortValues, 
DistribJoinFromCollectionTest, TestHashPartitioner, DOMUtilTest, 
TestSchemaVersionResource, MultiThreadedOCPTest, ResponseLogComponentTest, 
TestOverriddenPrefixQueryForCustomFieldType, ChaosMonkeyNothingIsSafeTest, 
CircularListTest, TestFunctionQuery, TestExtendedDismaxParser, 
TestConfigOverlay, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, 
OverseerTest, FullSolrCloudDistribCmdsTest, TestRandomDVFaceting, 
ShardRoutingCustomTest, TestStressVersions, TestRangeQuery, TestCoreContainer, 
TestSolr4Spatial, SolrCmdDistributorTest, BadIndexSchemaTest, 
ConvertedLegacyTest, TestSort, TestLazyCores, CurrencyFieldOpenExchangeTest, 
SolrIndexSplitterTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, 
SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, 
TestFoldingMultitermQuery, DocValuesTest, TestStressLucene, SuggesterWFSTTest, 
NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, QueryEqualityTest, 
DocValuesMultiTest, TestSurroundQueryParser, LukeRequestHandlerTest, 
FileBasedSpellCheckerTest, DocumentBuilderTest, 
FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, LoggingHandlerTest, 
JSONWriterTest, TestAnalyzedSuggestions, BinaryUpdateRequestHandlerTest, 
PingRequestHandlerTest, TestComponentsName, HighlighterConfigTest, 
CopyFieldTest, TestStressRecovery, TestSolrDeletionPolicy2, TestSearchPerf, 
TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, 
DirectSolrConnectionTest, TestPhraseSuggestions, TestXIncludeConfig, 
TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, 
TestBM25SimilarityFactory, TimeZoneUtilsTest, URLClassifyProcessorTest, 
CursorPagingTest, TestHighlightDedupGrouping, AliasIntegrationTest, 
CollectionsAPIAsyncDistributedZkTest, DeleteLastCustomShardedReplicaTest, 
DeleteReplicaTest, ExternalCollectionsTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, ReplicationFactorTest, 
SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, 
SolrXmlInZkTest, TestCloudPivotFacet, TestCryptoKeys, 
TestMiniSolrCloudClusterSSL, TestRebalanceLeaders, HdfsBasicDistributedZk2Test, 
HdfsChaosMonkeySafeLeaderTest, HdfsWriteToMultipleCollectionsTest, 
CachingDirectoryFactoryTest, TestInitParams, TestSolrConfigHandler]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSolrConfigHandler -Dtests.seed=3184DDE6E629C6E6 
-Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=US/Arizona 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | TestSolrConfigHandler (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf\params.json: 
java.nio.file.FileSystemException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf\params.json: The process 
cannot access the file because it is being used by another process.
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1\conf
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010\collection1
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001\tempDir-010
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrConfigHandler
 3184DDE6E629C6E6-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3184DDE6E629C6E6]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:294)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed on J0 in 8.32s, 4 tests, 1 error <<< FAILURES!

[...truncated 210 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:529: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:477: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:191: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:509:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1370:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:976:
 There were test failures: 474 suites, 1890 tests, 1 suite-level error, 1 
error, 57 ignored (30 assumptions)

Total time: 55 minutes 29 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_40 
-XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org