Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2072/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC

1 tests failed.
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([B672401FF0EC5C1A:E848A262CC7AA056]: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 20025 lines...]
   [junit4] Suite: org.apache.solr.search.function.TestFunctionQuery
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001
   [junit4]   2> 1359917 T7046 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (false)
   [junit4]   2> 1359918 T7046 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 1359918 T7046 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1359919 T7046 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1359919 T7046 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1359972 T7046 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1359985 T7046 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 6.0.0
   [junit4]   2> 1359990 T7046 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig-functionquery.xml
   [junit4]   2> 1359991 T7046 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema11.xml
   [junit4]   2> 1359998 T7046 oass.IndexSchema.readSchema [null] Schema 
name=example
   [junit4]   2> 1360035 T7046 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1360036 T7046 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1360041 T7046 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1360041 T7046 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr
   [junit4]   2> 1360042 T7046 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/'
   [junit4]   2> 1360057 T7046 oasc.CoreContainer.<init> New CoreContainer 
819525324
   [junit4]   2> 1360058 T7046 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/]
   [junit4]   2> 1360058 T7046 oasc.CoreContainer.load loading shared library: 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/lib
   [junit4]   2> 1360058 T7046 oasc.SolrResourceLoader.addToClassLoader WARN 
Can't find (or read) directory to add to classloader: lib (resolved as: 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/lib).
   [junit4]   2> 1360066 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 600000
   [junit4]   2> 1360066 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: null
   [junit4]   2> 1360066 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 60000
   [junit4]   2> 1360066 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1360066 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnections to: 10000
   [junit4]   2> 1360066 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1360067 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1360067 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1360067 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1360067 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1360068 T7046 oashc.HttpShardHandlerFactory.getParameter 
Setting useRetries to: false
   [junit4]   2> 1360068 T7046 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1360068 T7046 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1360069 T7046 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1360069 T7046 oasc.CoreContainer.load Node Name: testNode
   [junit4]   2> 1360070 T7046 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: 
{name=collection1, config=solrconfig-functionquery.xml, transient=false, 
schema=schema11.xml, loadOnStartup=true, instanceDir=collection1, 
collection=collection1, 
absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/,
 
dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001, shard=shard1}
   [junit4]   2> 1360070 T7047 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1360071 T7047 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1360071 T7047 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1360090 T7047 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 1360093 T7047 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 6.0.0
   [junit4]   2> 1360097 T7047 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig-functionquery.xml
   [junit4]   2> 1360097 T7047 oass.IndexSchema.readSchema Reading Solr Schema 
from 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema11.xml
   [junit4]   2> 1360102 T7047 oass.IndexSchema.readSchema [collection1] Schema 
name=example
   [junit4]   2> 1360137 T7047 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1360138 T7047 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1360141 T7047 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 1360142 T7047 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1360142 T7047 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/,
 
dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001/
   [junit4]   2> 1360142 T7047 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1360143 T7047 oasc.CachingDirectoryFactory.get return new 
directory for 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001
   [junit4]   2> 1360143 T7047 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001/index/
   [junit4]   2> 1360143 T7047 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001/index' doesn't exist. Creating new 
index...
   [junit4]   2> 1360143 T7047 oasc.CachingDirectoryFactory.get return new 
directory for 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001/index
   [junit4]   2> 1360143 T7047 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.AlcoholicMergePolicy: 
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=435078275, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1360144 T7047 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_1,generation=1}
   [junit4]   2> 1360144 T7047 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1360146 T7047 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1360154 T7047 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1360155 T7047 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1360156 T7047 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1360156 T7047 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1360163 T7047 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> 1360163 T7047 oasc.SolrCore.initStatsCache Using default 
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1360164 T7047 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1360164 T7047 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1360165 T7047 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=29, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=3.0302734375, 
floorSegmentMB=0.5830078125, forceMergeDeletesPctAllowed=25.668605188595798, 
segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3894119953048656
   [junit4]   2> 1360165 T7047 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_1,generation=1}
   [junit4]   2> 1360165 T7047 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1360165 T7047 oass.SolrIndexSearcher.<init> Opening 
Searcher@476d488f[collection1] main
   [junit4]   2> 1360166 T7047 oasr.ManagedResourceStorage.newStorageIO WARN 
Cannot write to config directory 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1360166 T7047 oasr.RestManager.init Initializing RestManager 
with initArgs: {}
   [junit4]   2> 1360166 T7047 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1360166 T7047 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1360166 T7047 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1360166 T7047 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1360167 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@476d488f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1360168 T7046 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 1360172 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testExternalFileFieldStringKeys
   [junit4]   2> ASYNC  NEW_CORE C2713 name=collection1 
org.apache.solr.core.SolrCore@4d4e927a
   [junit4]   2> 1360172 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[991]} 0 0
   [junit4]   2> 1360174 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[992]} 0 0
   [junit4]   2> 1360175 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[993]} 0 0
   [junit4]   2> 1360176 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360179 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_2,generation=2}
   [junit4]   2> 1360180 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 2
   [junit4]   2> 1360181 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@679a1f68[collection1] main
   [junit4]   2> 1360181 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360182 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@679a1f68[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c3)))}
   [junit4]   2> 1360182 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 1360197 T7046 C2713 oassf.FileFloatSource.getFloats Loaded 
external value source external_foo_extfs
   [junit4]   2> 1360197 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_extfs&indent=on&fl=*,score&rows=100&wt=xml} hits=3 
status=0 QTime=13 
   [junit4]   2> 1360198 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testExternalFileFieldStringKeys
   [junit4]   2> 1360201 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testExternalFileFieldNumericKey
   [junit4]   2> 1360201 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[991]} 0 0
   [junit4]   2> 1360202 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[992]} 0 0
   [junit4]   2> 1360202 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[993]} 0 0
   [junit4]   2> 1360202 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360207 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_3,generation=3}
   [junit4]   2> 1360207 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 3
   [junit4]   2> 1360208 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@381233d6[collection1] main
   [junit4]   2> 1360208 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360208 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@381233d6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c3)))}
   [junit4]   2> 1360209 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 1360228 T7046 C2713 oassf.FileFloatSource.getFloats ERROR 
Error loading external value source + fileName + 
org.apache.solr.common.SolrException: Invalid Number: 
   [junit4]   2> 1360229 T7046 C2713 oassf.FileFloatSource.getFloats Loaded 
external value source external_eff_trie
   [junit4]   2> 1360230 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}eff_trie&indent=on&fl=*,score&rows=100&wt=xml} hits=3 status=0 
QTime=20 
   [junit4]   2> 1360234 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testExternalFileFieldNumericKey
   [junit4]   2> 1360239 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testDegreeRads
   [junit4]   2> 1360239 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1360240 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1360241 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1360242 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360243 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_4,generation=4}
   [junit4]   2> 1360243 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 4
   [junit4]   2> 1360243 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@2bccb7e[collection1] main
   [junit4]   2> 1360244 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360244 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2bccb7e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c3)
 Uninverting(_2(6.0.0):c3)))}
   [junit4]   2> 1360245 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1360246 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}rad(x_td)&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1360247 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}rad(x_td)&fl=*,score&fq=id:2&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1360251 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}rad(x_td)&fl=*,score&fq=id:3&wt=xml} 
hits=1 status=0 QTime=4 
   [junit4]   2> 1360252 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}deg(y_td)&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1360252 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}deg(y_td)&fl=*,score&fq=id:2&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1360253 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}deg(y_td)&fl=*,score&fq=id:3&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1360253 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testDegreeRads
   [junit4]   2> 1360265 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testPseudoFieldFunctions
   [junit4]   2> 1360266 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1360267 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 1
   [junit4]   2> 1360267 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360270 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_5,generation=5}
   [junit4]   2> 1360270 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 5
   [junit4]   2> 1360270 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@5093b912[collection1] main
   [junit4]   2> 1360271 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5093b912[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c3)
 Uninverting(_2(6.0.0):c3/2:delGen=1) Uninverting(_3(6.0.0):c2)))}
   [junit4]   2> 1360271 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360271 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1360272 T7046 C2713 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> 1360277 T7046 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> 1360278 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testPseudoFieldFunctions
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestFunctionQuery 
-Dtests.method=testPseudoFieldFunctions -Dtests.seed=B672401FF0EC5C1A 
-Dtests.slow=true -Dtests.locale=ca -Dtests.timezone=Asia/Ulaanbaatar 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.03s J1 | TestFunctionQuery.testPseudoFieldFunctions <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: unexpected map keys 
[e] @ response/docs/[0]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([B672401FF0EC5C1A:E848A262CC7AA056]: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> 1360290 T7046 oas.SolrTestCaseJ4.setUp ###Starting testGeneral
   [junit4]   2> 1360290 T7046 C2713 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1360290 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1360291 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1360292 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1360292 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360295 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_5,generation=5}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_6,generation=6}
   [junit4]   2> 1360295 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 6
   [junit4]   2> 1360295 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@362163bc[collection1] main
   [junit4]   2> 1360296 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360296 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@362163bc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)))}
   [junit4]   2> 1360297 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360297 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[3]} 0 0
   [junit4]   2> 1360298 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[4]} 0 1
   [junit4]   2> 1360298 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360300 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_6,generation=6}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_7,generation=7}
   [junit4]   2> 1360300 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 7
   [junit4]   2> 1360301 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@1884cc0f[collection1] main
   [junit4]   2> 1360301 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360301 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1884cc0f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2)))}
   [junit4]   2> 1360302 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1360303 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5]} 0 0
   [junit4]   2> 1360303 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1360304 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360305 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_7,generation=7}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_8,generation=8}
   [junit4]   2> 1360305 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 8
   [junit4]   2> 1360306 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@62f9480b[collection1] main
   [junit4]   2> 1360307 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@62f9480b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2)))}
   [junit4]   2> 1360307 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360307 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 1360308 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}numdocs()&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1360310 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}maxdoc()&fl=*,score&fq=id:6&wt=xml} 
hits=1 status=0 QTime=2 
   [junit4]   2> 1360311 T7046 C2713 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> 1360313 T7046 C2713 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> 1360313 T7046 C2713 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> 1360318 T7046 C2713 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=4 
   [junit4]   2> 1360319 T7046 C2713 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> 1360319 T7046 C2713 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=0 
   [junit4]   2> 1360323 T7046 C2713 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> 1360331 T7046 C2713 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=7 
   [junit4]   2> 1360332 T7046 C2713 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=0 
   [junit4]   2> 1360333 T7046 C2713 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> 1360335 T7046 C2713 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=1 
   [junit4]   2> 1360336 T7046 C2713 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=1 
   [junit4]   2> 1360337 T7046 C2713 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=1 
   [junit4]   2> 1360338 T7046 C2713 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=1 
   [junit4]   2> 1360339 T7046 C2713 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> 1360340 T7046 C2713 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> 1360340 T7046 C2713 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> 1360341 T7046 C2713 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> 1360342 T7046 C2713 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> 1360342 T7046 C2713 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> 1360346 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}ms(2009-08-31T12:10:10.125Z/SECOND,2009-08-31T12:10:10.124Z/SECOND)&fl=*,score&fq=id:1&wt=xml}
 hits=1 status=0 QTime=2 
   [junit4]   2> 1360346 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q={!func}ms(NOW)&fl=*,score&NOW=1000&wt=xml} 
hits=6 status=0 QTime=0 
   [junit4]   2> 1360347 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100]} 0 0
   [junit4]   2> 1360348 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[101]} 0 0
   [junit4]   2> 1360348 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[102]} 0 0
   [junit4]   2> 1360349 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[103]} 0 0
   [junit4]   2> 1360350 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[104]} 0 0
   [junit4]   2> 1360350 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[105]} 0 0
   [junit4]   2> 1360351 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[106]} 0 1
   [junit4]   2> 1360351 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[107]} 0 0
   [junit4]   2> 1360351 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[108]} 0 0
   [junit4]   2> 1360352 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[109]} 0 1
   [junit4]   2> 1360353 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[110]} 0 1
   [junit4]   2> 1360353 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[111]} 0 0
   [junit4]   2> 1360353 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360356 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_8,generation=8}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_9,generation=9}
   [junit4]   2> 1360356 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 9
   [junit4]   2> 1360356 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@7f6ca51a[collection1] main
   [junit4]   2> 1360357 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7f6ca51a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2) 
Uninverting(_7(6.0.0):c12)))}
   [junit4]   2> 1360357 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360357 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1360358 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[120]} 0 0
   [junit4]   2> 1360359 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[121]} 0 0
   [junit4]   2> 1360359 T7046 C2713 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360361 T7046 C2713 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_9,generation=9}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_a,generation=10}
   [junit4]   2> 1360361 T7046 C2713 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 10
   [junit4]   2> 1360362 T7046 C2713 oass.SolrIndexSearcher.<init> Opening 
Searcher@2fcf8fca[collection1] main
   [junit4]   2> 1360363 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2fcf8fca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2) Uninverting(_7(6.0.0):c12) 
Uninverting(_8(6.0.0):c2)))}
   [junit4]   2> 1360364 T7046 C2713 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360364 T7046 C2713 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360365 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={qq=text:batman&q={!func}query($qq)&fl=*,score&fq=id:120&wt=xml} hits=1 
status=0 QTime=1 
   [junit4]   2> 1360369 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={qq=text:superman&q={!func}query($qq)&fl=*,score&fq=id:120&wt=xml} 
hits=1 status=0 QTime=1 
   [junit4]   2> 1360369 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={qq=text:superman&q={!frange+l%3D1+u%3D10}query($qq)&fl=*,score&fq=id:120&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1360370 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={qq=text:batman&q={!func}sub(div(sum(0.0,product(1,query($qq))),1),0)&fl=*,score&fq=id:120&wt=xml}
 hits=1 status=0 QTime=0 
   [junit4]   2> 1360371 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={qq=text:superman&q={!func}sub(div(sum(0.0,product(1,query($qq))),1),0)&fl=*,score&fq=id:120&wt=xml}
 hits=1 status=0 QTime=1 
   [junit4]   2> 1360372 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}add($v1,$v2)&fl=*,score&v1=add($v3,$v4)&fq=id:1&v2=1&v3=2&wt=xml&v4=5}
 hits=1 status=0 QTime=1 
   [junit4]   2> 1360372 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}dist(2,vector(1,1),$pt)&pt=3,1&fl=*,score&fq=id:1&wt=xml} 
hits=1 status=0 QTime=0 
   [junit4]   2> 1360373 T7046 C2713 oasc.SolrException.log Ignoring exception 
matching ignore_exception
   [junit4]   2> 1360374 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}10+wow+dude+ignore_exception&fl=*,score&wt=xml} status=400 
QTime=1 
   [junit4]   2> 1360374 T7046 oasc.SolrException.log Ignoring exception 
matching ignore_exception
   [junit4]   2> 1360375 T7046 C2713 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={qq=text:(batman+OR+superman)&q=*:*&sortfunc=query($qq)&fq=id:120+OR+id:121&sort={!func+v%3D$sortfunc}+desc&wt=xml}
 hits=2 status=0 QTime=1 
   [junit4]   2> 1360386 T7046 oas.SolrTestCaseJ4.tearDown ###Ending testGeneral
   [junit4]   2> 1360389 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testFunctions
   [junit4]   2> ASYNC  NEW_CORE C2714 name=collection1 
org.apache.solr.core.SolrCore@4d4e927a
   [junit4]   2> 1360389 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 0
   [junit4]   2> 1360390 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1360391 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1360391 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[0.0]} 0 0
   [junit4]   2> 1360392 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1360392 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[25.0]} 0 0
   [junit4]   2> 1360393 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[25.0]} 0 1
   [junit4]   2> 1360393 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5.0]} 0 0
   [junit4]   2> 1360394 T7046 C2714 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360397 T7046 C2714 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_a,generation=10}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_b,generation=11}
   [junit4]   2> 1360397 T7046 C2714 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 11
   [junit4]   2> 1360398 T7046 C2714 oass.SolrIndexSearcher.<init> Opening 
Searcher@41fa13b3[collection1] main
   [junit4]   2> 1360398 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@41fa13b3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2/1:delGen=1) 
Uninverting(_7(6.0.0):c12/1:delGen=1) Uninverting(_8(6.0.0):c2) 
Uninverting(_9(6.0.0):c8/2:delGen=1)))}
   [junit4]   2> 1360399 T7046 C2714 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360399 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360415 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[88.0]} 0 16
   [junit4]   2> 1360415 T7046 C2714 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360428 T7046 C2714 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_b,generation=11}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_c,generation=12}
   [junit4]   2> 1360428 T7046 C2714 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 12
   [junit4]   2> 1360429 T7046 C2714 oass.SolrIndexSearcher.<init> Opening 
Searcher@3ca2ca4b[collection1] main
   [junit4]   2> 1360429 T7046 C2714 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360429 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3ca2ca4b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2/1:delGen=1) 
Uninverting(_7(6.0.0):c12/1:delGen=1) Uninverting(_8(6.0.0):c2) 
Uninverting(_9(6.0.0):c8/2:delGen=1) Uninverting(_a(6.0.0):c1)))}
   [junit4]   2> 1360430 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 15
   [junit4]   2> 1360430 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_f&indent=on&fl=*,score&rows=100&wt=xml} hits=25 status=0 
QTime=0 
   [junit4]   2> 1360431 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360432 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}-1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360434 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360435 T7046 C2714 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=25 
status=0 QTime=0 
   [junit4]   2> 1360436 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360437 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sub(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=1 
   [junit4]   2> 1360438 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}product(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360439 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360451 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}log(foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=11 
   [junit4]   2> 1360452 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360453 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}abs(foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360455 T7046 C2714 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=25 
status=0 QTime=1 
   [junit4]   2> 1360456 T7046 C2714 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=25 
status=0 QTime=1 
   [junit4]   2> 1360458 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,foo_f)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=1 
   [junit4]   2> 1360459 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360472 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(abs(foo_f))&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=1 
   [junit4]   2> 1360473 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360473 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360474 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360477 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360478 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360479 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360488 T7046 C2714 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=25 status=0 QTime=7 
   [junit4]   2> 1360489 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360490 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360492 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}nvl(foo_f,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=1 
   [junit4]   2> 1360500 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360502 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360503 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360504 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360506 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360506 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360508 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360509 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 0
   [junit4]   2> 1360510 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1360510 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1360511 T7046 C2714 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360513 T7046 C2714 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_c,generation=12}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_d,generation=13}
   [junit4]   2> 1360514 T7046 C2714 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 13
   [junit4]   2> 1360514 T7046 C2714 oass.SolrIndexSearcher.<init> Opening 
Searcher@7e89e016[collection1] main
   [junit4]   2> 1360515 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7e89e016[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2/1:delGen=1) 
Uninverting(_7(6.0.0):c12/1:delGen=1) Uninverting(_8(6.0.0):c2) 
Uninverting(_9(6.0.0):c8/4:delGen=2) Uninverting(_a(6.0.0):c1) 
Uninverting(_b(6.0.0):c3/1:delGen=1)))}
   [junit4]   2> 1360515 T7046 C2714 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360515 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 1360516 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[0.0]} 0 0
   [junit4]   2> 1360516 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1360517 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[25.0]} 0 0
   [junit4]   2> 1360517 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5.0]} 0 0
   [junit4]   2> 1360518 T7046 C2714 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360521 T7046 C2714 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_d,generation=13}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_e,generation=14}
   [junit4]   2> 1360522 T7046 C2714 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 14
   [junit4]   2> 1360523 T7046 C2714 oass.SolrIndexSearcher.<init> Opening 
Searcher@378fc21a[collection1] main
   [junit4]   2> 1360523 T7046 C2714 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360523 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@378fc21a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2/1:delGen=1) 
Uninverting(_7(6.0.0):c12/1:delGen=1) Uninverting(_8(6.0.0):c2) 
Uninverting(_a(6.0.0):c1) Uninverting(_b(6.0.0):c3/1:delGen=1) 
Uninverting(_c(6.0.0):c4)))}
   [junit4]   2> 1360524 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 1360524 T7046 C2714 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360525 T7046 C2714 oasu.DirectUpdateHandler2.commit No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1360525 T7046 C2714 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 1360526 T7046 C2714 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360526 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 2
   [junit4]   2> 1360527 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[88.0]} 0 1
   [junit4]   2> 1360527 T7046 C2714 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360530 T7046 C2714 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_e,generation=14}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_f,generation=15}
   [junit4]   2> 1360530 T7046 C2714 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 15
   [junit4]   2> 1360530 T7046 C2714 oass.SolrIndexSearcher.<init> Opening 
Searcher@4b2bb202[collection1] main
   [junit4]   2> 1360531 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4b2bb202[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c2)
 Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2/1:delGen=1) 
Uninverting(_7(6.0.0):c12/1:delGen=1) Uninverting(_8(6.0.0):c2) 
Uninverting(_b(6.0.0):c3/1:delGen=1) Uninverting(_c(6.0.0):c4) 
Uninverting(_d(6.0.0):c1)))}
   [junit4]   2> 1360531 T7046 C2714 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360532 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360532 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_tf&indent=on&fl=*,score&rows=100&wt=xml} hits=25 status=0 
QTime=0 
   [junit4]   2> 1360533 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360534 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}-1.414213&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360535 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sum(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360536 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360537 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360537 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sub(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360538 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}product(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} 
hits=25 status=0 QTime=0 
   [junit4]   2> 1360539 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360540 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}log(foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360542 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360544 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}abs(foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360545 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360546 T7046 C2714 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=25 
status=0 QTime=0 
   [junit4]   2> 1360550 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,foo_tf)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=2 
   [junit4]   2> 1360551 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}div(1,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=0 
   [junit4]   2> 1360552 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}sqrt(abs(foo_tf))&indent=on&fl=*,score&rows=100&wt=xml} 
hits=25 status=0 QTime=0 
   [junit4]   2> 1360553 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360554 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360556 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360558 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360558 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360560 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360571 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360573 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360574 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360575 T7046 C2714 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}nvl(foo_tf,1)&indent=on&fl=*,score&rows=100&wt=xml} hits=25 
status=0 QTime=1 
   [junit4]   2> 1360576 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360601 T7046 C2714 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=25 status=0 QTime=24 
   [junit4]   2> 1360613 T7046 C2714 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=25 status=0 QTime=10 
   [junit4]   2> 1360625 T7046 C2714 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=25 status=0 QTime=11 
   [junit4]   2> 1360626 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360628 T7046 C2714 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=25 status=0 QTime=0 
   [junit4]   2> 1360629 T7046 C2714 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=25 status=0 QTime=1 
   [junit4]   2> 1360630 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testFunctions
   [junit4]   2> 1360633 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testFieldValueSourceParser
   [junit4]   2> 1360637 T7046 C2714 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1360638 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 1360640 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 1
   [junit4]   2> 1360640 T7046 C2714 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[100.0]} 0 0
   [junit4]   2> ASYNC  NEW_CORE C2715 name=collection1 
org.apache.solr.core.SolrCore@4d4e927a
   [junit4]   2> 1360656 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[-4.0]} 0 0
   [junit4]   2> 1360657 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[0.0]} 0 1
   [junit4]   2> 1360657 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1360657 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[10.0]} 0 0
   [junit4]   2> 1360658 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[25.0]} 0 0
   [junit4]   2> 1360658 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[5.0]} 0 0
   [junit4]   2> 1360659 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[77.0]} 0 0
   [junit4]   2> 1360659 T7046 C2715 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360661 T7046 C2715 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_f,generation=15}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_g,generation=16}
   [junit4]   2> 1360662 T7046 C2715 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 16
   [junit4]   2> 1360662 T7046 C2715 oass.SolrIndexSearcher.<init> Opening 
Searcher@f9c6407[collection1] main
   [junit4]   2> 1360663 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@f9c6407[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(6.0.0):c9/2:delGen=1)))}
   [junit4]   2> 1360663 T7046 C2715 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360664 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360665 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1.0]} 0 0
   [junit4]   2> 1360666 T7046 C2715 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360671 T7046 C2715 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_g,generation=16}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_h,generation=17}
   [junit4]   2> 1360671 T7046 C2715 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 17
   [junit4]   2> 1360671 T7046 C2715 oass.SolrIndexSearcher.<init> Opening 
Searcher@402edd13[collection1] main
   [junit4]   2> 1360672 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@402edd13[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(6.0.0):c9/2:delGen=1)
 Uninverting(_f(6.0.0):c1)))}
   [junit4]   2> 1360672 T7046 C2715 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360672 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 6
   [junit4]   2> 1360672 T7046 C2715 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=0 
   [junit4]   2> 1360674 T7046 C2715 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=1 
   [junit4]   2> 1360684 T7046 C2715 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=0 
   [junit4]   2> 1360685 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testFieldValueSourceParser
   [junit4]   2> 1360699 T7046 oas.SolrTestCaseJ4.setUp ###Starting testFuncs
   [junit4]   2> 1360702 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 3
   [junit4]   2> 1360702 T7046 C2715 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360705 T7046 C2715 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_h,generation=17}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_i,generation=18}
   [junit4]   2> 1360705 T7046 C2715 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 18
   [junit4]   2> 1360705 T7046 C2715 oass.SolrIndexSearcher.<init> Opening 
Searcher@5e7f9d49[collection1] main
   [junit4]   2> 1360706 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5e7f9d49[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(6.0.0):c9/2:delGen=1)
 Uninverting(_g(6.0.0):c1)))}
   [junit4]   2> 1360706 T7046 C2715 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360707 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360708 T7046 C2715 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> 1360708 T7046 C2715 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> 1360709 T7046 C2715 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=0 
   [junit4]   2> 1360709 T7046 C2715 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> 1360710 T7046 C2715 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> 1360710 T7046 C2715 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=0 
   [junit4]   2> 1360712 T7046 C2715 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=1 
   [junit4]   2> 1360712 T7046 C2715 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> 1360713 T7046 C2715 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> 1360713 T7046 C2715 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> 1360714 T7046 C2715 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> 1360715 T7046 C2715 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=0 
   [junit4]   2> 1360716 T7046 C2715 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=0 
   [junit4]   2> 1360717 T7046 C2715 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=1 
   [junit4]   2> 1360718 T7046 C2715 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=1 
   [junit4]   2> 1360718 T7046 C2715 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> 1360719 T7046 C2715 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=0 
   [junit4]   2> 1360720 T7046 C2715 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> 1360720 T7046 C2715 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> 1360721 T7046 C2715 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=1 
   [junit4]   2> 1360722 T7046 C2715 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> 1360723 T7046 C2715 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=1 
   [junit4]   2> 1360723 T7046 C2715 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=0 
   [junit4]   2> 1360723 T7046 C2715 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> 1360725 T7046 C2715 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> 1360725 T7046 C2715 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=0 
   [junit4]   2> 1360726 T7046 C2715 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> 1360727 T7046 C2715 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> 1360732 T7046 C2715 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=5 
   [junit4]   2> 1360733 T7046 C2715 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> 1360733 T7046 C2715 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> 1360733 T7046 oas.SolrTestCaseJ4.tearDown ###Ending testFuncs
   [junit4]   2> 1360736 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testBooleanFunctions
   [junit4]   2> 1360737 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 1
   [junit4]   2> 1360737 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1360737 T7046 C2715 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360740 T7046 C2715 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_i,generation=18}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_j,generation=19}
   [junit4]   2> 1360740 T7046 C2715 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 19
   [junit4]   2> 1360741 T7046 C2715 oass.SolrIndexSearcher.<init> Opening 
Searcher@165f2eab[collection1] main
   [junit4]   2> 1360742 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@165f2eab[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(6.0.0):c9/2:delGen=1)
 Uninverting(_h(6.0.0):c2)))}
   [junit4]   2> 1360742 T7046 C2715 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360742 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360746 T7046 C2715 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:1&fl=a:testfunc(1)&wt=xml} hits=1 status=0 
QTime=3 
   [junit4]   2> 1360747 T7046 C2715 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=0 
   [junit4]   2> 1360757 T7046 C2715 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=0 
   [junit4]   2> 1360758 T7046 C2715 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> 1360760 T7046 C2715 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=0 
   [junit4]   2> 1360761 T7046 C2715 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=0 
   [junit4]   2> 1360762 T7046 C2715 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=1 
   [junit4]   2> 1360762 T7046 C2715 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> 1360763 T7046 C2715 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=1 
   [junit4]   2> 1360764 T7046 C2715 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> 1360765 T7046 C2715 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> 1360765 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testBooleanFunctions
   [junit4]   2> 1360781 T7046 oas.SolrTestCaseJ4.setUp ###Starting 
testMissingFieldFunctionBehavior
   [junit4]   2> 1360781 T7046 C2715 oasu.DirectUpdateHandler2.deleteAll 
[collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1360782 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 1360782 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {add=[1]} 0 0
   [junit4]   2> 1360783 T7046 C2715 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1360785 T7046 C2715 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_j,generation=19}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@4951e2ec 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8e68d29),segFN=segments_k,generation=20}
   [junit4]   2> 1360785 T7046 C2715 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 20
   [junit4]   2> 1360786 T7046 C2715 oass.SolrIndexSearcher.<init> Opening 
Searcher@7ffc1ea8[collection1] main
   [junit4]   2> 1360786 T7048 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7ffc1ea8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(6.0.0):c1)))}
   [junit4]   2> 1360786 T7046 C2715 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1360787 T7046 C2715 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 1360788 T7046 C2715 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> 1360788 T7046 C2715 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=0 
   [junit4]   2> 1360797 T7046 C2715 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> 1360798 T7046 C2715 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> 1360799 T7046 C2715 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> 1360800 T7046 C2715 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> 1360801 T7046 C2715 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> 1360802 T7046 C2715 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> 1360803 T7046 C2715 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> 1360803 T7046 C2715 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=0 
   [junit4]   2> 1360805 T7046 C2715 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=1 
   [junit4]   2> 1360806 T7046 C2715 oasc.SolrCore.execute [collection1] 
webapp=null path=null params={q=id:1&fl=noval_if:if(no__vals____td,42,-99)&fl

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

mit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1361326 T7046 C2717 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 1361326 T7046 C2717 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1361327 T7046 C2717 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={=&wt=xml} {commit=} 0 3
   [junit4]   2> 1361327 T7046 C2717 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {} 0 0
   [junit4]   2> 1361330 T7046 C2717 oassf.FileFloatSource.getFloats Loaded 
external value source external_foo_extf
   [junit4]   2> 1361330 T7046 C2717 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_extf&indent=on&fl=*,score&rows=100&wt=xml} hits=21 
status=0 QTime=2 
   [junit4]   2> 1361336 T7046 C2717 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1361336 T7046 C2717 oasu.DirectUpdateHandler2.commit No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1361336 T7046 C2717 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 1361337 T7046 C2717 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1361337 T7046 C2717 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={=&wt=xml} {commit=} 0 1
   [junit4]   2> 1361337 T7046 C2717 oasup.LogUpdateProcessor.finish 
[collection1] webapp=null path=null params={} {} 0 0
   [junit4]   2> 1361341 T7046 C2717 oassf.FileFloatSource.getFloats Loaded 
external value source external_foo_extf
   [junit4]   2> 1361341 T7046 C2717 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q={!func}foo_extf&indent=on&fl=*,score&rows=100&wt=xml} hits=21 
status=0 QTime=4 
   [junit4]   2> 1361345 T7046 oas.SolrTestCaseJ4.tearDown ###Ending 
testExternalField
   [junit4]   2> 1361346 T7046 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 1361346 T7046 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=819525324
   [junit4]   2> 1361352 T7046 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@4d4e927a
   [junit4]   2> 1361353 T7046 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=44,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=120,cumulative_deletesById=0,cumulative_deletesByQuery=5,cumulative_errors=0}
   [junit4]   2> 1361353 T7046 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1361353 T7046 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1361353 T7046 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1361354 T7046 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1361354 T7046 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1361354 T7046 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001 
[CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001;done=false>>]
   [junit4]   2> 1361355 T7046 oasc.CachingDirectoryFactory.close Closing 
directory: 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001
   [junit4]   2> 1361355 T7046 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001/index 
[CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 1361355 T7046 oasc.CachingDirectoryFactory.close Closing 
directory: 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.function.TestFunctionQuery
 B672401FF0EC5C1A-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=DefaultSimilarity, locale=ca, timezone=Asia/Ulaanbaatar
   [junit4]   2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 
(64-bit)/cpus=3,threads=1,free=202415936,total=396177408
   [junit4]   2> NOTE: All tests run in this JVM: [TestFieldTypeResource, 
DirectUpdateHandlerOptimizeTest, CoreAdminRequestStatusTest, 
TestCopyFieldCollectionResource, TestRealTimeGet, SolrIndexConfigTest, 
OpenExchangeRatesOrgProviderTest, HdfsCollectionsAPIDistributedZkTest, 
OverseerRolesTest, StandardRequestHandlerTest, 
TestReversedWildcardFilterFactory, TestSchemaVersionResource, 
TestSolrConfigHandler, PluginInfoTest, BinaryUpdateRequestHandlerTest, 
BadCopyFieldTest, TestHighlightDedupGrouping, TestUtils, 
TestUniqueKeyFieldResource, TestSolrDeletionPolicy1, 
SignatureUpdateProcessorFactoryTest, TestFiltering, DateFieldTest, 
SuggesterTSTTest, PolyFieldTest, MoreLikeThisHandlerTest, AutoCommitTest, 
BasicDistributedZk2Test, TestClusterStateMutator, CSVRequestHandlerTest, 
TestLuceneMatchVersion, ShardSplitTest, ExitableDirectoryReaderTest, 
TestClassNameShortening, TestRTGBase, TestCloudInspectUtil, 
TestManagedSchemaFieldTypeResource, TestDefaultSimilarityFactory, 
FieldMutatingUpdateProcessorTest, TestHdfsUpdateLog, 
TestSuggestSpellingConverter, TestSerializedLuceneMatchVersion, TestRecovery, 
TestLRUStatsCache, TestImplicitCoreProperties, UpdateParamsTest, 
CustomCollectionTest, TestFieldCollectionResource, 
TestSimpleTrackingShardHandler, TestQuerySenderNoQuery, TestFastWriter, 
FullSolrCloudDistribCmdsTest, ExternalFileFieldSortTest, 
DeleteInactiveReplicaTest, HdfsBasicDistributedZk2Test, 
SegmentsInfoRequestHandlerTest, DisMaxRequestHandlerTest, 
TestFoldingMultitermQuery, LeaderElectionIntegrationTest, ZkNodePropsTest, 
DirectSolrSpellCheckerTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
BasicZkTest, AnalyticsMergeStrategyTest, SpellingQueryConverterTest, 
HdfsWriteToMultipleCollectionsTest, TestExpandComponent, TestOrdValues, 
BasicFunctionalityTest, TestRestManager, DistributedFacetPivotLongTailTest, 
AnalyticsQueryTest, FileBasedSpellCheckerTest, AsyncMigrateRouteKeyTest, 
TestSolrDeletionPolicy2, TriLevelCompositeIdRoutingTest, MinimalSchemaTest, 
TestInitParams, TestRemoteStreaming, TestSchemaResource, 
DocExpirationUpdateProcessorFactoryTest, CurrencyFieldXmlFileTest, 
TestCursorMarkWithoutUniqueKey, TestBadConfig, TestFuzzyAnalyzedSuggestions, 
TestLMDirichletSimilarityFactory, StatelessScriptUpdateProcessorFactoryTest, 
TestSearcherReuse, TestSortingResponseWriter, MergeStrategyTest, 
TestPivotHelperCode, StatsComponentTest, 
DistributedQueryComponentCustomSortTest, TestDynamicLoading, 
QueryResultKeyTest, TestLeaderElectionZkExpiry, TestMiniSolrCloudClusterSSL, 
TestValueSourceCache, TestAnalyzeInfixSuggestions, RAMDirectoryFactoryTest, 
TestTrie, SimpleMLTQParserTest, TestChildDocTransformer, HighlighterTest, 
WordBreakSolrSpellCheckerTest, LoggingHandlerTest, CopyFieldTest, 
TestSolrQueryParser, SchemaVersionSpecificBehaviorTest, QueryEqualityTest, 
DistributedQueryComponentOptimizationTest, BasicDistributedZkTest, 
ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, 
OpenCloseCoreStressTest, RecoveryZkTest, TestZkChroot, TestRandomDVFaceting, 
TestDistributedGrouping, DistributedSpellCheckComponentTest, 
TermVectorComponentDistributedTest, TestStressReorder, TestJoin, TestReload, 
TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest, 
TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, SpellCheckComponentTest, 
TestGroupingSearch, SolrCmdDistributorTest, ConvertedLegacyTest, 
TestFunctionQuery]
   [junit4] Completed on J1 in 1.47s, 15 tests, 1 error <<< FAILURES!

[...truncated 640 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following 
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following 
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following 
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The 
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:191: The 
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: 
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1351: 
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:958: 
There were test failures: 476 suites, 1893 tests, 1 error, 53 ignored (20 
assumptions)

Total time: 65 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 
-XX:-UseCompressedOops -XX:+UseSerialGC
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

Reply via email to