You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/04/25 21:58:07 UTC
[JENKINS] Lucene-Solr-Tests-5.x-Java7 - Build # 3010 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/3010/
1 tests failed.
REGRESSION: org.apache.solr.search.facet.TestJsonFacets.testComplex
Error Message:
mismatch: 'civic'!='a' @ facets/makes/buckets/[0]/models/buckets/[1]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'civic'!='a' @ facets/makes/buckets/[0]/models/buckets/[1]/val
at __randomizedtesting.SeedInfo.seed([9E61A60C86CF0D3D:7FBEA390AA81415E]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:160)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:142)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:288)
at org.apache.solr.search.facet.TestJsonFacets.testComplex(TestJsonFacets.java:157)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10419 lines...]
[junit4] Suite: org.apache.solr.search.facet.TestJsonFacets
[junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001
[junit4] 2> 1908127 T5138 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 1908127 T5138 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1908128 T5138 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1908129 T5138 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1908130 T5138 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1908276 T5138 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1908310 T5138 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 1908363 T5138 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1908364 T5138 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema_latest.xml
[junit4] 2> 1908374 T5138 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 1908579 T5138 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
[junit4] 2> 1908584 T5138 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1908595 T5138 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1908599 T5138 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1908603 T5138 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1908603 T5138 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr
[junit4] 2> 1908603 T5138 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/'
[junit4] 2> 1908617 T5138 oasc.CoreContainer.<init> New CoreContainer 102026952
[junit4] 2> 1908617 T5138 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/]
[junit4] 2> 1908618 T5138 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/lib
[junit4] 2> 1908618 T5138 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/lib).
[junit4] 2> 1908627 T5138 oashc.HttpShardHandlerFactory.init created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1908636 T5138 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 1908636 T5138 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1908637 T5138 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1908637 T5138 oasc.CoreContainer.load Node Name: testNode
[junit4] 2> 1908639 T5138 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {instanceDir=collection1, name=collection1, shard=shard1, transient=false, schema=schema_latest.xml, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/, loadOnStartup=true, config=solrconfig-tlog.xml, collection=collection1, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001}
[junit4] 2> 1908639 T5139 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1908640 T5139 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1908640 T5139 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1908641 T5139 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1908661 T5139 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1908679 T5139 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 1908721 T5139 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 1908722 T5139 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema_latest.xml
[junit4] 2> 1908729 T5139 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 1908873 T5139 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
[junit4] 2> 1908877 T5139 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1908887 T5139 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1908891 T5139 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1908894 T5139 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/
[junit4] 2> 1908894 T5139 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1908894 T5139 c:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 1908895 T5139 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8fb3349
[junit4] 2> 1908895 T5139 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001
[junit4] 2> 1908895 T5139 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001/index/
[junit4] 2> 1908895 T5139 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 1908896 T5139 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001/index
[junit4] 2> 1908896 T5139 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=39.9716796875, floorSegmentMB=1.072265625, forceMergeDeletesPctAllowed=23.436061935160133, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8038232545920658
[junit4] 2> 1908897 T5139 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_1,generation=1}
[junit4] 2> 1908897 T5139 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1908901 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1908901 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1908901 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1908901 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1908901 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1908902 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1908902 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1908902 T5139 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1908902 T5139 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1908920 T5139 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1908922 T5139 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1908924 T5139 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1908926 T5139 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1908929 T5139 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /schema,standard,/admin/threads,/admin/ping,/admin/luke,/get,/admin/properties,/admin/logging,/admin/system,/update/json,/update,/admin/mbeans,/update/csv,/admin/plugins,/admin/file,/admin/segments,/update/json/docs,/config,/replication
[junit4] 2> 1908931 T5139 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1908932 T5139 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1908932 T5139 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1908932 T5139 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1908932 T5139 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1908933 T5139 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=39.525390625, floorSegmentMB=0.4033203125, forceMergeDeletesPctAllowed=7.334760511678847, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1908933 T5139 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_1,generation=1}
[junit4] 2> 1908934 T5139 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1908934 T5139 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@16dbb827[collection1] main
[junit4] 2> 1908934 T5139 c:collection1 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1908935 T5139 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1908935 T5139 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1908935 T5139 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1908935 T5139 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1908936 T5140 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16dbb827[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1908936 T5139 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1908938 T5138 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 1908942 T5138 oas.SolrTestCaseJ4.setUp ###Starting testStatsSimple
[junit4] 2> ASYNC NEW_CORE C930 name=collection1 org.apache.solr.core.SolrCore@191bfb1d
[junit4] 2> 1908944 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1499453079208067072)} 0 2
[junit4] 2> 1908948 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1 (1499453079213309952)]} 0 1
[junit4] 2> 1908949 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2 (1499453079215407104)]} 0 0
[junit4] 2> 1908951 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3 (1499453079216455680)]} 0 1
[junit4] 2> 1908952 T5138 C930 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1908954 T5138 C930 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_2,generation=2}
[junit4] 2> 1908954 T5138 C930 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1908955 T5138 C930 oass.SolrIndexSearcher.<init> Opening Searcher@795147fe[collection1] main
[junit4] 2> 1908956 T5138 C930 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1908956 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@795147fe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3)))}
[junit4] 2> 1908957 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 1908959 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4 (1499453079224844288)]} 0 1
[junit4] 2> 1908961 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5 (1499453079227990016)]} 0 0
[junit4] 2> 1908962 T5138 C930 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1908964 T5138 C930 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_3,generation=3}
[junit4] 2> 1908964 T5138 C930 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1908965 T5138 C930 oass.SolrIndexSearcher.<init> Opening Searcher@d596105[collection1] main
[junit4] 2> 1908965 T5138 C930 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1908966 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d596105[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_1(5.2.0):C2)))}
[junit4] 2> 1908966 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 1908969 T5138 C930 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6 (1499453079234281472)]} 0 2
[junit4] 2> 1908970 T5138 C930 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C931 name=collection1 org.apache.solr.core.SolrCore@191bfb1d
[junit4] 2> 1908977 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_4,generation=4}
[junit4] 2> 1908978 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 1908979 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@7d95bfaa[collection1] main
[junit4] 2> 1908979 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1908980 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7d95bfaa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_1(5.2.0):C2) Uninverting(_2(5.2.0):C1)))}
[junit4] 2> 1908980 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
[junit4] 2> 1909103 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&json.facet={x:'sum(num_d)'}&json.facet={y:'min(num_d)'}&q=*:*&wt=xml} hits=6 status=0 QTime=122
[junit4] 2> 1909174 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&json.facet={+++cat:{terms:{field:'cat_s',+method:stream+}},+cat2:{terms:{field:'cat_s',+method:stream,+sort:'index+asc'+}},+cat3:{terms:{field:'cat_s',+method:stream,+mincount:3+}},+cat4:{terms:{field:'cat_s',+method:stream,+prefix:B+}},+cat5:{terms:{field:'cat_s',+method:stream,+offset:1+}}+}&q=*:*&wt=xml} hits=6 status=0 QTime=68
[junit4] 2> 1909192 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&json.facet={+++cat:{terms:{field:'cat_s',+sort:'index+asc',+facet:{where:{terms:{field:where_s,method:stream}}}+++}}}&q=*:*&wt=xml} hits=6 status=0 QTime=17
[junit4] 2> 1909193 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&json.facet={+++cat:{terms:{field:'cat_s',+method:stream,+facet:{where:{terms:{field:where_s,method:stream}}}+++}}}&q=*:*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 1909204 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&json.facet={+++cat:{terms:{field:'cat_s',+method:stream,+facet:{++where:{terms:{field:where_s,method:stream,+facet:{x:'max(num_d)'}+++++}}}+++}}}&q=*:*&wt=xml} hits=6 status=0 QTime=10
[junit4] 2> 1909205 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&json.facet={+++cat:{terms:{field:'cat_s',+method:stream,+facet:{+y:'min(num_d)',++where:{terms:{field:where_s,method:stream,+facet:{x:'max(num_d)'}+++++}}}+++}}}&facet=true&q=*:*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 1909207 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={fq=cat_s:A&q=*:*&wt=xml} hits=2 status=0 QTime=1
[junit4] 2> 1909207 T5138 oas.SolrTestCaseJ4.tearDown ###Ending testStatsSimple
[junit4] 2> 1909211 T5138 oas.SolrTestCaseJ4.setUp ###Starting testStats
[junit4] 2> 1909214 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@d31f5ad[collection1] realtime
[junit4] 2> 1909215 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1499453079492231168)} 0 3
[junit4] 2> 1909221 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[1 (1499453079499571200)]} 0 1
[junit4] 2> 1909226 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[2 (1499453079505862656)]} 0 1
[junit4] 2> 1909226 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[3 (1499453079505862657)]} 0 0
[junit4] 2> 1909227 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909229 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_5,generation=5}
[junit4] 2> 1909229 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 1909230 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@17c92adb[collection1] main
[junit4] 2> 1909231 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1909231 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17c92adb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.2.0):C3)))}
[junit4] 2> 1909232 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 1909235 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[4 (1499453079513202688)]} 0 2
[junit4] 2> 1909236 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[5 (1499453079515299840)]} 0 1
[junit4] 2> 1909236 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909239 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_6,generation=6}
[junit4] 2> 1909239 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 1909240 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@61431439[collection1] main
[junit4] 2> 1909240 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1909241 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@61431439[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.2.0):C3) Uninverting(_4(5.2.0):C2)))}
[junit4] 2> 1909241 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 1909244 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[6 (1499453079522639872)]} 0 2
[junit4] 2> 1909244 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909247 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_7,generation=7}
[junit4] 2> 1909247 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 1909248 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@3aee927f[collection1] main
[junit4] 2> 1909248 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1909249 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3aee927f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.2.0):C3) Uninverting(_4(5.2.0):C2) Uninverting(_5(5.2.0):C1)))}
[junit4] 2> 1909249 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 1909267 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7BcatA%3A%7Bquery%3A%7Bq%3A%27%24%7Bcat_s%7D%3AA%27%7D%7D%2C++catA2%3A%7Bquery%3A%7Bquery%3A%27%24%7Bcat_s%7D%3AA%27%7D%7D%2C++catA3%3A%7Bquery%3A%27%24%7Bcat_s%7D%3AA%27%7D++++%7D&wt=json&indent=true} hits=6 status=0 QTime=17
[junit4] 2> 1909269 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7B+catB%3A%7Btype%3Aquery%2C+q%3A%27%24%7Bcat_s%7D%3AB%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909270 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A%282+3%29&json.facet=%7B+catB%3A%7Bquery%3A%7Bq%3A%27%24%7Bcat_s%7D%3AB%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+%7D%7D%7D&wt=json&indent=true} hits=2 status=0 QTime=1
[junit4] 2> 1909270 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7B+catB%3A%7Bquery%3A%7Bq%3A%27%24%7Bcat_s%7D%3AB%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%7Bq%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909271 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bc1%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%7D%2C+c2%3A%7Bfield%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%7D%7D%2C+c3%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%7D++%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909272 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+mincount%3A3%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909273 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A1&json.facet=%7Bf1%3A%7Bterms%3A%27%24%7Bcat_s%7D%27%7D%7D&wt=json&indent=true} hits=1 status=0 QTime=1
[junit4] 2> 1909273 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A1&json.facet=%7BprocessEmpty%3Atrue%2C+f1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+mincount%3A0%7D%7D%7D&wt=json&indent=true} hits=1 status=0 QTime=0
[junit4] 2> 1909274 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A1&json.facet=%7BprocessEmpty%3Atrue%2C+f1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+mincount%3A0%2C+allBuckets%3Atrue%2C+facet%3A%7Bn1%3A%27sum%28%24%7Bnum_d%7D%29%27%7D++%7D%7D%7D&wt=json&indent=true} hits=1 status=0 QTime=1
[junit4] 2> 1909275 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27sum%28%24%7Bnum_d%7D%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27sum%28%24%7Bnum_d%7D%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909310 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27x+desc%27%2C+facet%3A%7Bx%3A%27min%28%24%7Bnum_d%7D%29%27%7D++%7D+%2C+f2%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27x+desc%27%2C+facet%3A%7Bx%3A%27max%28%24%7Bnum_d%7D%29%27%7D++%7D++%2C+f3%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27x+desc%27%2C+facet%3A%7Bx%3A%27unique%28%24%7Bwhere_s%7D%29%27%7D++%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=35
[junit4] 2> 1909322 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27avg%28add%28%24%7Bnum_d%7D%2C%24%7Bnum_d%7D%29%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27avg%28add%28%24%7Bnum_d%7D%2C%24%7Bnum_d%7D%29%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=12
[junit4] 2> 1909333 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=10
[junit4] 2> 1909338 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%2C0%2C100%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%2C0%2C100%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=4
[junit4] 2> 1909340 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+desc%27+%7D++%7D+++++++++++%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+asc%27++%7D++%7D+++++++++++%2C+f3%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27index+asc%27++%7D++%7D+++++++++++%2C+f4%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27index+desc%27+%7D++%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909341 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A1+id%3A6&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+desc%27+%7D++%7D+++++++++++%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+asc%27++%7D++%7D%7D&wt=json&indent=true} hits=2 status=0 QTime=1
[junit4] 2> 1909343 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bcat%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D++++%7D+++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909344 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A%282+5+4%29&json.facet=%7Bcat%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D++++%7D+++%7D%7D+%7D&wt=json&indent=true} hits=3 status=0 QTime=1
[junit4] 2> 1909345 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3As%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909346 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3Attt%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909347 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3Aaaaaaa%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909348 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3Azzzzzz%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909349 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bnoexist%7D%2C+missing%3Atrue%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909350 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909350 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue%2C+facet%3A%7Bx%3A%27sum%28%24%7Bnum_d%7D%29%27%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909351 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue%2C+prefix%3Aon%2C+facet%3A%7Bx%3A%27sum%28%24%7Bnum_d%7D%29%27%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909352 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue%2C+prefix%3Appp%2C+facet%3A%7Bx%3A%27sum%28%24%7Bnum_d%7D%29%27%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909353 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&facet=true&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bcat_s%7D%2C+numBuckets%3Atrue%2C+limit%3A1%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909354 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&facet=true&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bcat_s%7D%2C+numBuckets%3Atrue%2C+prefix%3AB%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909355 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&facet=true&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bcat_s%7D%2C+numBuckets%3Atrue%2C+mincount%3A3%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909409 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Btype%3Arange%2C+field%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=54
[junit4] 2> 1909411 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Btype%3Arange%2C+field%3A%24%7Bdate%7D%2C+start%3A%272001-01-01T00%3A00%3A00Z%27%2C+end%3A%272003-01-01T00%3A00%3A00Z%27%2C+gap%3A%27%2B1YEAR%27%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909413 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Btype%3Arange%2C+field%3A%24%7Bdate%7D%2C+start%3A%272002-01-01T00%3A00%3A00Z%27%2C+end%3A%272005-01-01T00%3A00%3A00Z%27%2C+gap%3A%27%2B1YEAR%27%2C+++other%3Aall%2C+facet%3A%7B+x%3A%27avg%28%24%7Bnum_d%7D%29%27+%7D+%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 1909414 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+include%3Aupper%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909415 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+++facet%3A%7B+x%3A%27sum%28%24%7Bnum_i%7D%29%27%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909417 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+other%3Aall%2C+++facet%3A%7B+x%3A%27sum%28%24%7Bnum_i%7D%29%27%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909418 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A%283+4+6%29&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+other%3Aall%2C+++facet%3A%7B+x%3A%27sum%28%24%7Bnum_i%7D%29%27%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+++%7D%7D%7D&wt=json&indent=true} hits=3 status=0 QTime=1
[junit4] 2> 1909429 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7B+sum1%3A%27sum%28%24%7Bnum_d%7D%29%27%2C+sumsq1%3A%27sumsq%28%24%7Bnum_d%7D%29%27%2C+avg1%3A%27avg%28%24%7Bnum_d%7D%29%27%2C+min1%3A%27min%28%24%7Bnum_d%7D%29%27%2C+max1%3A%27max%28%24%7Bnum_d%7D%29%27%2C+numwhere%3A%27unique%28%24%7Bwhere_s%7D%29%27%2C+unique_num_i%3A%27unique%28%24%7Bnum_i%7D%29%27%2C+unique_num_d%3A%27unique%28%24%7Bnum_d%7D%29%27%2C+unique_date%3A%27unique%28%24%7Bdate%7D%29%27%2C++med%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%2C+perc%3A%27percentile%28%24%7Bnum_d%7D%2C0%2C50.0%2C100%29%27+%7D&wt=json&indent=true} hits=6 status=0 QTime=10
[junit4] 2> 1909430 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3ADOESNOTEXIST&json.facet=%7B+sum1%3A%27sum%28%24%7Bnum_d%7D%29%27%2C+sumsq1%3A%27sumsq%28%24%7Bnum_d%7D%29%27%2C+avg1%3A%27avg%28%24%7Bnum_d%7D%29%27%2C+min1%3A%27min%28%24%7Bnum_d%7D%29%27%2C+max1%3A%27max%28%24%7Bnum_d%7D%29%27%2C+numwhere%3A%27unique%28%24%7Bwhere_s%7D%29%27%2C+unique_num_i%3A%27unique%28%24%7Bnum_i%7D%29%27%2C+unique_num_d%3A%27unique%28%24%7Bnum_d%7D%29%27%2C+unique_date%3A%27unique%28%24%7Bdate%7D%29%27%2C++med%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%2C+perc%3A%27percentile%28%24%7Bnum_d%7D%2C0%2C50.0%2C100%29%27+%7D&wt=json&indent=true} hits=0 status=0 QTime=0
[junit4] 2> 1909443 T5138 C931 oassf.UnInvertedField.<init> UnInverted multi-valued field {field=multi_ss,memSize=4515,tindexSize=41,time=5,phase1=5,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 2> 1909444 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bcat%3A%7Bterms%3A%7Bfield%3A%27%24%7Bmulti_ss%7D%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D++++%7D+++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=13
[junit4] 2> 1909451 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bx%3A%27unique%28%24%7Bmulti_ss%7D%29%27%2C+y%3A%7Bquery%3A%7Bq%3A%27id%3A2%27%2C+facet%3A%7Bx%3A%27unique%28%24%7Bmulti_ss%7D%29%27%7D+%7D%7D+++%7D&wt=json&indent=true} hits=6 status=0 QTime=6
[junit4] 2> 1909452 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bx%3A%7Bterms%3A%7Bfield%3A%27%24%7Bmulti_ss%7D%27%2CallBuckets%3Atrue%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909458 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&facet=true&facet.version=2&facet.field=%7B%21key%3Df1%7D%24%7Bcat_s%7D&facet.mincount=3&wt=json&indent=true} hits=6 status=0 QTime=6
[junit4] 2> 1909459 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&facet=true&facet.version=2&facet.field=%7B%21key%3Df1%7D%24%7Bsuper_s%7D&facet.prefix=s&facet.mincount=0&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909470 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&facet=true&facet.version=2&facet.range=%7B%21key%3Df%7D%24%7Bnum_d%7D&facet.range.start=-5&facet.range.end=10&facet.range.gap=5&f.f.facet.stat=x%3Asum%28%24%7Bnum_i%7D%29&subfacet.f.query=%7B%21key%3Dny%7D%24%7Bwhere_s%7D%3ANY&wt=json&indent=true} hits=6 status=0 QTime=11
[junit4] 2> 1909471 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=*%3A*&facet=true&facet.version=2&facet.field=%7B%21key%3Df1%7D%24%7Bcat_s%7D&facet.field=%7B%21key%3Df2%7D%24%7Bcat_s%7D&f.f1.facet.sort=n1+desc&f.f1.facet.sort=n1+asc&facet.stat=n1%3Asum%28%24%7Bnum_d%7D%29&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909473 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&q=id%3A%283+4+6%29&facet=true&facet.version=2&facet.range=%7B%21key%3Df%7D%24%7Bnum_d%7D&facet.range.start=-5&facet.range.end=10&facet.range.gap=5&f.f.facet.stat=x%3Asum%28%24%7Bnum_i%7D%29&subfacet.f.query=%7B%21key%3Dny%7D%24%7Bwhere_s%7D%3ANY&facet.range.other=all&wt=json&indent=true} hits=3 status=0 QTime=2
[junit4] 2> 1909476 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@3724ad25[collection1] realtime
[junit4] 2> 1909476 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1499453079765909504)} 0 2
[junit4] 2> 1909482 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[1 (1499453079773249536)]} 0 1
[junit4] 2> 1909483 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[2 (1499453079774298112)]} 0 1
[junit4] 2> 1909483 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[3 (1499453079775346688)]} 0 0
[junit4] 2> 1909483 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909485 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_8,generation=8}
[junit4] 2> 1909486 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 1909487 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@51c9e3b4[collection1] main
[junit4] 2> 1909488 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1909489 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51c9e3b4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.2.0):C3)))}
[junit4] 2> 1909489 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 1909492 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[4 (1499453079783735296)]} 0 2
[junit4] 2> 1909493 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[5 (1499453079785832448)]} 0 1
[junit4] 2> 1909493 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909496 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_9,generation=9}
[junit4] 2> 1909497 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 1909497 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@571096e5[collection1] main
[junit4] 2> 1909498 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1909499 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@571096e5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.2.0):C3) Uninverting(_7(5.2.0):C2)))}
[junit4] 2> 1909499 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 1909502 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[6 (1499453079793172480)]} 0 2
[junit4] 2> 1909503 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909506 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_a,generation=10}
[junit4] 2> 1909506 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 1909506 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@69ba95f3[collection1] main
[junit4] 2> 1909507 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1909508 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@69ba95f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(5.2.0):C3) Uninverting(_7(5.2.0):C2) Uninverting(_8(5.2.0):C1)))}
[junit4] 2> 1909508 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 1909510 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7BcatA%3A%7Bquery%3A%7Bq%3A%27%24%7Bcat_s%7D%3AA%27%7D%7D%2C++catA2%3A%7Bquery%3A%7Bquery%3A%27%24%7Bcat_s%7D%3AA%27%7D%7D%2C++catA3%3A%7Bquery%3A%27%24%7Bcat_s%7D%3AA%27%7D++++%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909511 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7B+catB%3A%7Btype%3Aquery%2C+q%3A%27%24%7Bcat_s%7D%3AB%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909512 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A%282+3%29&json.facet=%7B+catB%3A%7Bquery%3A%7Bq%3A%27%24%7Bcat_s%7D%3AB%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+%7D%7D%7D&wt=json&indent=true} hits=2 status=0 QTime=0
[junit4] 2> 1909513 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7B+catB%3A%7Bquery%3A%7Bq%3A%27%24%7Bcat_s%7D%3AB%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%7Bq%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909514 T5138 C931 oassf.UnInvertedField.<init> UnInverted multi-valued field {field=cat_ss,memSize=4446,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=1,termInstances=2,uses=0}
[junit4] 2> 1909515 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bc1%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%7D%2C+c2%3A%7Bfield%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%7D%7D%2C+c3%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%7D++%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909516 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+mincount%3A3%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909517 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A1&json.facet=%7Bf1%3A%7Bterms%3A%27%24%7Bcat_s%7D%27%7D%7D&wt=json&indent=true} hits=1 status=0 QTime=1
[junit4] 2> 1909518 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A1&json.facet=%7BprocessEmpty%3Atrue%2C+f1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+mincount%3A0%7D%7D%7D&wt=json&indent=true} hits=1 status=0 QTime=1
[junit4] 2> 1909518 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A1&json.facet=%7BprocessEmpty%3Atrue%2C+f1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+mincount%3A0%2C+allBuckets%3Atrue%2C+facet%3A%7Bn1%3A%27sum%28%24%7Bnum_d%7D%29%27%7D++%7D%7D%7D&wt=json&indent=true} hits=1 status=0 QTime=0
[junit4] 2> 1909519 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27sum%28%24%7Bnum_d%7D%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27sum%28%24%7Bnum_d%7D%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909521 T5138 C931 oassf.UnInvertedField.<init> UnInverted multi-valued field {field=where_ss,memSize=4448,tindexSize=42,time=0,phase1=0,nTerms=2,bigTerms=1,termInstances=2,uses=0}
[junit4] 2> 1909521 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27x+desc%27%2C+facet%3A%7Bx%3A%27min%28%24%7Bnum_d%7D%29%27%7D++%7D+%2C+f2%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27x+desc%27%2C+facet%3A%7Bx%3A%27max%28%24%7Bnum_d%7D%29%27%7D++%7D++%2C+f3%3A%7Btype%3Aterms%2C+field%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27x+desc%27%2C+facet%3A%7Bx%3A%27unique%28%24%7Bwhere_s%7D%29%27%7D++%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909523 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27avg%28add%28%24%7Bnum_d%7D%2C%24%7Bnum_d%7D%29%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27avg%28add%28%24%7Bnum_d%7D%2C%24%7Bnum_d%7D%29%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909524 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909525 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+desc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%2C0%2C100%29%27%7D++%7D%7D+%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27n1+asc%27%2C+facet%3A%7Bn1%3A%27percentile%28%24%7Bnum_d%7D%2C50%2C0%2C100%29%27%7D++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909527 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+desc%27+%7D++%7D+++++++++++%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+asc%27++%7D++%7D+++++++++++%2C+f3%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27index+asc%27++%7D++%7D+++++++++++%2C+f4%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27index+desc%27+%7D++%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909528 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A1+id%3A6&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+desc%27+%7D++%7D+++++++++++%2C+f2%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+sort%3A%27count+asc%27++%7D++%7D%7D&wt=json&indent=true} hits=2 status=0 QTime=1
[junit4] 2> 1909529 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bcat%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D++++%7D+++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909531 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A%282+5+4%29&json.facet=%7Bcat%3A%7Bterms%3A%7Bfield%3A%27%24%7Bcat_s%7D%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D++++%7D+++%7D%7D+%7D&wt=json&indent=true} hits=3 status=0 QTime=1
[junit4] 2> 1909532 T5138 C931 oassf.UnInvertedField.<init> UnInverted multi-valued field {field=super_ss,memSize=4358,tindexSize=46,time=0,phase1=0,nTerms=5,bigTerms=0,termInstances=5,uses=0}
[junit4] 2> 1909532 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3As%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909533 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3Attt%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909534 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3Aaaaaaa%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909534 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsuper_s%7D%2C+prefix%3Azzzzzz%2C+mincount%3A0+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909535 T5138 C931 oassf.UnInvertedField.<init> UnInverted multi-valued field {field=noexist_ss,memSize=4288,tindexSize=0,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1909536 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bnoexist%7D%2C+missing%3Atrue%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909536 T5138 C931 oassf.UnInvertedField.<init> UnInverted multi-valued field {field=sparse_ss,memSize=4355,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=0,termInstances=2,uses=0}
[junit4] 2> 1909537 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue+%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909538 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue%2C+facet%3A%7Bx%3A%27sum%28%24%7Bnum_d%7D%29%27%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909539 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue%2C+prefix%3Aon%2C+facet%3A%7Bx%3A%27sum%28%24%7Bnum_d%7D%29%27%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909539 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bsparse_s%7D%2C+missing%3Atrue%2C+prefix%3Appp%2C+facet%3A%7Bx%3A%27sum%28%24%7Bnum_d%7D%29%27%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909540 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bcat_s%7D%2C+numBuckets%3Atrue%2C+limit%3A1%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909541 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bcat_s%7D%2C+numBuckets%3Atrue%2C+prefix%3AB%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909542 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf1%3A%7Bterms%3A%7Bfield%3A%24%7Bcat_s%7D%2C+numBuckets%3Atrue%2C+mincount%3A3%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909543 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Btype%3Arange%2C+field%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909545 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Btype%3Arange%2C+field%3A%24%7Bdate%7D%2C+start%3A%272001-01-01T00%3A00%3A00Z%27%2C+end%3A%272003-01-01T00%3A00%3A00Z%27%2C+gap%3A%27%2B1YEAR%27%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909547 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Btype%3Arange%2C+field%3A%24%7Bdate%7D%2C+start%3A%272002-01-01T00%3A00%3A00Z%27%2C+end%3A%272005-01-01T00%3A00%3A00Z%27%2C+gap%3A%27%2B1YEAR%27%2C+++other%3Aall%2C+facet%3A%7B+x%3A%27avg%28%24%7Bnum_d%7D%29%27+%7D+%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 1909548 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+include%3Aupper%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909550 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+++facet%3A%7B+x%3A%27sum%28%24%7Bnum_i%7D%29%27%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909552 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+other%3Aall%2C+++facet%3A%7B+x%3A%27sum%28%24%7Bnum_i%7D%29%27%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+++%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 1909554 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A%283+4+6%29&json.facet=%7Bf%3A%7Brange%3A%7Bfield%3A%24%7Bnum_d%7D%2C+start%3A-5%2C+end%3A10%2C+gap%3A5%2C+other%3Aall%2C+++facet%3A%7B+x%3A%27sum%28%24%7Bnum_i%7D%29%27%2C+ny%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANY%27%7D%7D+++%7D%7D%7D&wt=json&indent=true} hits=3 status=0 QTime=1
[junit4] 2> 1909555 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7B+sum1%3A%27sum%28%24%7Bnum_d%7D%29%27%2C+sumsq1%3A%27sumsq%28%24%7Bnum_d%7D%29%27%2C+avg1%3A%27avg%28%24%7Bnum_d%7D%29%27%2C+min1%3A%27min%28%24%7Bnum_d%7D%29%27%2C+max1%3A%27max%28%24%7Bnum_d%7D%29%27%2C+numwhere%3A%27unique%28%24%7Bwhere_s%7D%29%27%2C+unique_num_i%3A%27unique%28%24%7Bnum_i%7D%29%27%2C+unique_num_d%3A%27unique%28%24%7Bnum_d%7D%29%27%2C+unique_date%3A%27unique%28%24%7Bdate%7D%29%27%2C++med%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%2C+perc%3A%27percentile%28%24%7Bnum_d%7D%2C0%2C50.0%2C100%29%27+%7D&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 1909557 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3ADOESNOTEXIST&json.facet=%7B+sum1%3A%27sum%28%24%7Bnum_d%7D%29%27%2C+sumsq1%3A%27sumsq%28%24%7Bnum_d%7D%29%27%2C+avg1%3A%27avg%28%24%7Bnum_d%7D%29%27%2C+min1%3A%27min%28%24%7Bnum_d%7D%29%27%2C+max1%3A%27max%28%24%7Bnum_d%7D%29%27%2C+numwhere%3A%27unique%28%24%7Bwhere_s%7D%29%27%2C+unique_num_i%3A%27unique%28%24%7Bnum_i%7D%29%27%2C+unique_num_d%3A%27unique%28%24%7Bnum_d%7D%29%27%2C+unique_date%3A%27unique%28%24%7Bdate%7D%29%27%2C++med%3A%27percentile%28%24%7Bnum_d%7D%2C50%29%27%2C+perc%3A%27percentile%28%24%7Bnum_d%7D%2C0%2C50.0%2C100%29%27+%7D&wt=json&indent=true} hits=0 status=0 QTime=1
[junit4] 2> 1909558 T5138 C931 oassf.UnInvertedField.<init> UnInverted multi-valued field {field=multi_ss,memSize=4515,tindexSize=41,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 2> 1909559 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bcat%3A%7Bterms%3A%7Bfield%3A%27%24%7Bmulti_ss%7D%27%2C+facet%3A%7Bnj%3A%7Bquery%3A%27%24%7Bwhere_s%7D%3ANJ%27%7D%7D++++%7D+++%7D%7D+%7D&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 1909560 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bx%3A%27unique%28%24%7Bmulti_ss%7D%29%27%2C+y%3A%7Bquery%3A%7Bq%3A%27id%3A2%27%2C+facet%3A%7Bx%3A%27unique%28%24%7Bmulti_ss%7D%29%27%7D+%7D%7D+++%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909561 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&json.facet=%7Bx%3A%7Bterms%3A%7Bfield%3A%27%24%7Bmulti_ss%7D%27%2CallBuckets%3Atrue%7D%7D%7D&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909562 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&facet.version=2&facet.field=%7B%21key%3Df1%7D%24%7Bcat_s%7D&facet.mincount=3&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909563 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&facet.version=2&facet.field=%7B%21key%3Df1%7D%24%7Bsuper_s%7D&facet.prefix=s&facet.mincount=0&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909565 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&facet.version=2&facet.range=%7B%21key%3Df%7D%24%7Bnum_d%7D&facet.range.start=-5&facet.range.end=10&facet.range.gap=5&f.f.facet.stat=x%3Asum%28%24%7Bnum_i%7D%29&subfacet.f.query=%7B%21key%3Dny%7D%24%7Bwhere_s%7D%3ANY&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 1909566 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=*%3A*&facet.version=2&facet.field=%7B%21key%3Df1%7D%24%7Bcat_s%7D&facet.field=%7B%21key%3Df2%7D%24%7Bcat_s%7D&f.f1.facet.sort=n1+desc&f.f1.facet.sort=n1+asc&facet.stat=n1%3Asum%28%24%7Bnum_d%7D%29&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 1909569 T5138 C931 oasc.SolrCore.execute [collection1] webapp=null path=null params={facet=true&facet=true&rows=0&noexist=noexist_ss&cat_s=cat_ss&where_s=where_ss&num_d=num_f&num_i=num_l&super_s=super_ss&val_b=val_b&date=date_dt&sparse_s=sparse_ss&multi_ss=multi_ss&q=id%3A%283+4+6%29&facet.version=2&facet.range=%7B%21key%3Df%7D%24%7Bnum_d%7D&facet.range.start=-5&facet.range.end=10&facet.range.gap=5&f.f.facet.stat=x%3Asum%28%24%7Bnum_i%7D%29&subfacet.f.query=%7B%21key%3Dny%7D%24%7Bwhere_s%7D%3ANY&facet.range.other=all&wt=json&indent=true} hits=3 status=0 QTime=2
[junit4] 2> 1909572 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@6ce0dacb[collection1] realtime
[junit4] 2> 1909572 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1499453079866572800)} 0 3
[junit4] 2> 1909575 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[1 (1499453079870767104)]} 0 1
[junit4] 2> 1909576 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[2 (1499453079872864256)]} 0 0
[junit4] 2> 1909577 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[3 (1499453079873912832)]} 0 1
[junit4] 2> 1909577 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909580 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_b,generation=11}
[junit4] 2> 1909581 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 1909589 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@26d67996[collection1] main
[junit4] 2> 1909590 T5138 C931 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1909591 T5140 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26d67996[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(5.2.0):C3)))}
[junit4] 2> 1909591 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 1909595 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[4 (1499453079890690048)]} 0 2
[junit4] 2> 1909596 T5138 C931 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[5 (1499453079893835776)]} 0 0
[junit4] 2> 1909597 T5138 C931 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1909601 T5138 C931 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ba55d5a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5593e682),segFN=segments_c,generation=12}
[junit4] 2> 1909602 T5138 C931 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 1909602 T5138 C931 oass.SolrIndexSearcher.<init> Opening Searcher@92
[...truncated too long message...]
olr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/s2-001/./collection1/data
[junit4] 2> 1914288 T5138 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/s2-001/./collection1/data/index [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/s2-001/./collection1/data/index;done=false>>]
[junit4] 2> 1914288 T5138 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/s2-001/./collection1/data/index
[junit4] 2> 1914289 T5138 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1914481 T5138 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 1914481 T5138 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=102026952
[junit4] 2> 1914481 T5138 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@191bfb1d
[junit4] 2> 1914482 T5138 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=23,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=144,cumulative_deletesById=0,cumulative_deletesByQuery=7,cumulative_errors=0,transaction_logs_total_size=7300,transaction_logs_total_number=5}
[junit4] 2> 1914482 T5138 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1914482 T5138 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1914482 T5138 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1914484 T5138 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1914485 T5138 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1914485 T5138 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001 [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001;done=false>>]
[junit4] 2> 1914486 T5138 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001
[junit4] 2> 1914486 T5138 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001/index [CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001/index;done=false>>]
[junit4] 2> 1914486 T5138 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001/init-core-data-001/index
[junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets 9E61A60C86CF0D3D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {date_dtds=Lucene50(blocksize=128), sparse_sds=PostingsFormat(name=Memory doPackFST= false), date_dtd=PostingsFormat(name=Memory doPackFST= false), where_sds=FSTOrd50, date_dt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), super_ss=Lucene50(blocksize=128), sparse_sd=Lucene50(blocksize=128), super_sds=FSTOrd50, cat_sd=FSTOrd50, where_ss=FSTOrd50, id=PostingsFormat(name=Memory doPackFST= false), make_s=FSTOrd50, super_sd=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), num_id=Lucene50(blocksize=128), cat_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), num_d=PostingsFormat(name=Memory doPackFST= false), multi_ss=Lucene50(blocksize=128), _version_=FSTOrd50, num_l=PostingsFormat(name=Memory doPackFST= false), num_dd=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), where_sd=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), super_s=PostingsFormat(name=Memory doPackFST= false), cat_sds=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), cost_f=Lucene50(blocksize=128), num_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), val_b=FSTOrd50, num_i=FSTOrd50, cat_s=Lucene50(blocksize=128), model_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), where_s=PostingsFormat(name=Memory doPackFST= false), sparse_ss=PostingsFormat(name=Memory doPackFST= false), multi_sds=Lucene50(blocksize=128), sparse_s=FSTOrd50}, docValues:{super_sd=DocValuesFormat(name=Direct), date_dtds=DocValuesFormat(name=Lucene50), num_id=DocValuesFormat(name=Lucene50), sparse_sds=DocValuesFormat(name=Memory), date_dtd=DocValuesFormat(name=Memory), num_dd=DocValuesFormat(name=Direct), where_sd=DocValuesFormat(name=Direct), where_sds=DocValuesFormat(name=Asserting), cat_sds=DocValuesFormat(name=Direct), sparse_sd=DocValuesFormat(name=Lucene50), super_sds=DocValuesFormat(name=Asserting), multi_sds=DocValuesFormat(name=Lucene50), cat_sd=DocValuesFormat(name=Asserting)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=vi_VN, timezone=Africa/Luanda
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=150055712,total=292552704
[junit4] 2> NOTE: All tests run in this JVM: [TestCustomSort, TestFieldResource, TestShardHandlerFactory, SolrIndexSplitterTest, TestSolrDeletionPolicy2, TestAnalyzedSuggestions, TestWriterPerf, LeaderInitiatedRecoveryOnCommitTest, OverseerRolesTest, SpatialHeatmapFacetsTest, SyncSliceTest, ChaosMonkeyNothingIsSafeTest, CSVRequestHandlerTest, BadCopyFieldTest, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, TestStressRecovery, TestQuerySenderNoQuery, TestMiniSolrCloudClusterSSL, TestCloudManagedSchema, HdfsThreadLeakTest, TestNRTOpen, RollingRestartTest, AtomicUpdatesTest, TestSolrQueryParser, RecoveryZkTest, TestJettySolrRunner, DistributedFacetPivotLargeTest, SpatialRPTFieldTypeTest, SolrCoreTest, TestPseudoReturnFields, OpenCloseCoreStressTest, TestRangeQuery, TestJoin, TestManagedSchemaDynamicFieldResource, HdfsNNFailoverTest, TestBM25SimilarityFactory, URLClassifyProcessorTest, HardAutoCommitTest, EnumFieldTest, OpenExchangeRatesOrgProviderTest, TestShortCircuitedRequests, TestCSVLoader, TestSchemaResource, TestSimpleQParserPlugin, TestBulkSchemaAPI, RankQueryTest, TestConfigReload, TestReqParamsAPI, TestFunctionQuery, UpdateRequestProcessorFactoryTest, SolrCoreCheckLockOnStartupTest, TestFieldTypeCollectionResource, AnalyticsMergeStrategyTest, SSLMigrationTest, TestSolrDeletionPolicy1, MoreLikeThisHandlerTest, TestRandomDVFaceting, TestConfigOverlay, FileUtilsTest, RecoveryAfterSoftCommitTest, TestDynamicLoading, DistribCursorPagingTest, CloudExitableDirectoryReaderTest, OverseerStatusTest, TestManagedSchemaFieldResource, DistributedTermsComponentTest, StatsComponentTest, DeleteLastCustomShardedReplicaTest, TestStressReorder, TestStressVersions, TestZkChroot, ExitableDirectoryReaderTest, CachingDirectoryFactoryTest, TestJsonFacets]
[junit4] Completed [313/484] on J1 in 6.39s, 4 tests, 1 error <<< FAILURES!
[...truncated 537 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:536: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:484: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:229: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:511: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1434: The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:991: There were test failures: 484 suites, 1946 tests, 1 error, 68 ignored (26 assumptions)
Total time: 53 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #3009
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 226598836 bytes
Compression is 0.0%
Took 41 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure