You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/04/12 04:47:55 UTC

[JENKINS] Lucene-Solr-5.x-Linux (64bit/jdk1.8.0_60-ea-b06) - Build # 12105 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/12105/
Java: 64bit/jdk1.8.0_60-ea-b06 -XX:+UseCompressedOops -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.solr.search.facet.TestJsonFacets.testComplex

Error Message:
mismatch: 'accord'!='a' @ facets/makes/buckets/[0]/models/buckets/[1]/val

Stack Trace:
java.lang.RuntimeException: mismatch: 'accord'!='a' @ facets/makes/buckets/[0]/models/buckets/[1]/val
	at __randomizedtesting.SeedInfo.seed([B566B8DDFE30CA8C:54B9BD41D27E86EF]: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:155)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java: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 9931 lines...]
   [junit4] Suite: org.apache.solr.search.facet.TestJsonFacets
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001
   [junit4]   2> 310848 T2426 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 310848 T2426 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 310848 T2426 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 310848 T2426 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 310859 T2426 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
   [junit4]   2> 310861 T2426 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
   [junit4]   2> 310866 T2426 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 310866 T2426 oass.IndexSchema.readSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema_latest.xml
   [junit4]   2> 310869 T2426 oass.IndexSchema.readSchema [null] Schema name=example
   [junit4]   2> 310904 T2426 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
   [junit4]   2> 310906 T2426 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 310909 T2426 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 310910 T2426 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 310911 T2426 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 310911 T2426 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 310912 T2426 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
   [junit4]   2> 310920 T2426 oasc.CoreContainer.<init> New CoreContainer 347156364
   [junit4]   2> 310920 T2426 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
   [junit4]   2> 310920 T2426 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 310920 T2426 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib).
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 600000
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 60000
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 310924 T2426 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 310925 T2426 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 310925 T2426 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
   [junit4]   2> 310928 T2426 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 310928 T2426 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 310928 T2426 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 310928 T2426 oasc.CoreContainer.load Node Name: testNode
   [junit4]   2> 310929 T2426 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, transient=false, schema=schema_latest.xml, loadOnStartup=true, instanceDir=collection1, collection=collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001, shard=shard1}
   [junit4]   2> 310929 T2427 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 310930 T2427 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 310930 T2427 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 310940 T2427 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
   [junit4]   2> 310942 T2427 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
   [junit4]   2> 310948 T2427 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 310949 T2427 oass.IndexSchema.readSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema_latest.xml
   [junit4]   2> 310951 T2427 oass.IndexSchema.readSchema [collection1] Schema name=example
   [junit4]   2> 310988 T2427 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
   [junit4]   2> 310990 T2427 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 310993 T2427 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 310994 T2427 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 310995 T2427 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 310995 T2427 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 310995 T2427 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001/
   [junit4]   2> 310996 T2427 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5755d26d
   [junit4]   2> 310996 T2427 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001
   [junit4]   2> 310996 T2427 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001/index/
   [junit4]   2> 310996 T2427 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001/index' doesn't exist. Creating new index...
   [junit4]   2> 310996 T2427 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001/index
   [junit4]   2> 310997 T2427 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 310997 T2427 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@1145239f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c46dbf6),segFN=segments_1,generation=1}
   [junit4]   2> 310997 T2427 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 310999 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 310999 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 310999 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 310999 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311000 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311000 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 311000 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311000 T2427 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311000 T2427 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 311001 T2427 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311002 T2427 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311002 T2427 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311003 T2427 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311004 T2427 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 311005 T2427 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 311005 T2427 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 311005 T2427 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
   [junit4]   2> 311005 T2427 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 311006 T2427 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 311006 T2427 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=73.474609375, floorSegmentMB=0.2216796875, forceMergeDeletesPctAllowed=11.675276646635503, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21491526645436299
   [junit4]   2> 311006 T2427 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@1145239f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c46dbf6),segFN=segments_1,generation=1}
   [junit4]   2> 311006 T2427 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 311006 T2427 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@1c6fc5eb[collection1] main
   [junit4]   2> 311007 T2427 c:collection1 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 311007 T2427 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 311007 T2427 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 311007 T2427 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 311007 T2427 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 311007 T2427 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 311008 T2428 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c6fc5eb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 311008 T2426 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 311010 T2426 oas.SolrTestCaseJ4.setUp ###Starting testDistrib
   [junit4]   2> 311016 T2426 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 311017 T2426 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51126
   [junit4]   2> 311018 T2426 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=51126}
   [junit4]   2> 311018 T2426 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 311018 T2426 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/'
   [junit4]   2> 311027 T2426 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/solr.xml
   [junit4]   2> 311030 T2426 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/.
   [junit4]   2> 311030 T2426 oasc.CoreContainer.<init> New CoreContainer 1470756559
   [junit4]   2> 311030 T2426 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/]
   [junit4]   2> 311031 T2426 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/lib
   [junit4]   2> 311031 T2426 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/lib).
   [junit4]   2> 311036 T2426 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 311037 T2426 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 311037 T2426 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 311037 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 311037 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
   [junit4]   2> 311037 T2426 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 311038 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 311038 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 311038 T2426 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 311038 T2426 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 311038 T2426 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
   [junit4]   2> 311039 T2426 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 311039 T2426 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 311040 T2426 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 311040 T2426 oasc.CoreContainer.load Node Name: 127.0.0.1
   [junit4]   2> 311040 T2426 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/.
   [junit4]   2> 311041 T2426 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1, loadOnStartup=true, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/, schema=schema_latest.xml, transient=false, dataDir=data/}
   [junit4]   2> 311041 T2426 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/
   [junit4]   2> 311041 T2426 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 311042 T2439 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/'
   [junit4]   2> 311052 T2439 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
   [junit4]   2> 311055 T2439 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
   [junit4]   2> 311059 T2439 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 311060 T2439 oass.IndexSchema.readSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/conf/schema_latest.xml
   [junit4]   2> 311063 T2439 oass.IndexSchema.readSchema [collection1] Schema name=example
   [junit4]   2> 311096 T2439 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
   [junit4]   2> 311098 T2439 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 311103 T2439 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 311104 T2439 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 311106 T2439 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/
   [junit4]   2> 311106 T2439 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 311107 T2439 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/, dataDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/data/
   [junit4]   2> 311107 T2439 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5755d26d
   [junit4]   2> 311108 T2439 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/data
   [junit4]   2> 311108 T2439 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/data/index/
   [junit4]   2> 311108 T2439 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 311108 T2439 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/data/index
   [junit4]   2> 311109 T2439 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 311109 T2439 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_1,generation=1}
   [junit4]   2> 311109 T2439 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 311113 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 311113 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 311114 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 311114 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311122 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311122 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 311122 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311123 T2439 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311123 T2439 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 311124 T2439 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311125 T2439 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311126 T2439 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311127 T2439 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311130 T2439 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 311131 T2439 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 311131 T2439 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 311132 T2439 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
   [junit4]   2> 311132 T2439 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 311133 T2439 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 311133 T2439 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=73.474609375, floorSegmentMB=0.2216796875, forceMergeDeletesPctAllowed=11.675276646635503, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21491526645436299
   [junit4]   2> 311133 T2439 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_1,generation=1}
   [junit4]   2> 311134 T2439 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 311134 T2439 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@8dd2ea9[collection1] main
   [junit4]   2> 311134 T2439 c:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/conf
   [junit4]   2> 311134 T2439 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/conf}
   [junit4]   2> 311134 T2439 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001/./collection1/conf
   [junit4]   2> 311135 T2439 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 311135 T2439 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 311135 T2440 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8dd2ea9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 311136 T2439 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 311136 T2426 oass.SolrDispatchFilter.init user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 311136 T2426 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 311136 T2426 oas.SolrTestCaseHS$SolrInstance.start ===> Started solr server port=51126 home=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s0-001
   [junit4]   2> 311140 T2426 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 311142 T2426 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40760
   [junit4]   2> 311142 T2426 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=40760}
   [junit4]   2> 311142 T2426 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 311142 T2426 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/'
   [junit4]   2> 311153 T2426 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/solr.xml
   [junit4]   2> 311156 T2426 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/.
   [junit4]   2> 311156 T2426 oasc.CoreContainer.<init> New CoreContainer 1983927656
   [junit4]   2> 311156 T2426 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/]
   [junit4]   2> 311156 T2426 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/lib
   [junit4]   2> 311157 T2426 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/lib).
   [junit4]   2> 311161 T2426 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 311161 T2426 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 311161 T2426 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 311161 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 311161 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
   [junit4]   2> 311161 T2426 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 311162 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 311162 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 311162 T2426 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 311162 T2426 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 311162 T2426 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
   [junit4]   2> 311163 T2426 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 311164 T2426 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 311164 T2426 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 311165 T2426 oasc.CoreContainer.load Node Name: 127.0.0.1
   [junit4]   2> 311165 T2426 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/.
   [junit4]   2> 311166 T2426 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1, loadOnStartup=true, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/, schema=schema_latest.xml, transient=false, dataDir=data/}
   [junit4]   2> 311166 T2426 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/
   [junit4]   2> 311167 T2426 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 311167 T2451 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/'
   [junit4]   2> 311178 T2451 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
   [junit4]   2> 311181 T2451 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
   [junit4]   2> 311186 T2451 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 311186 T2451 oass.IndexSchema.readSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/conf/schema_latest.xml
   [junit4]   2> 311188 T2451 oass.IndexSchema.readSchema [collection1] Schema name=example
   [junit4]   2> 311218 T2451 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
   [junit4]   2> 311220 T2451 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 311224 T2451 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 311225 T2451 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 311226 T2451 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/
   [junit4]   2> 311226 T2451 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 311226 T2451 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/, dataDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/data/
   [junit4]   2> 311227 T2451 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5755d26d
   [junit4]   2> 311227 T2451 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/data
   [junit4]   2> 311227 T2451 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/data/index/
   [junit4]   2> 311227 T2451 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 311227 T2451 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/data/index
   [junit4]   2> 311228 T2451 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 311228 T2451 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@b183833 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@598b5aa2),segFN=segments_1,generation=1}
   [junit4]   2> 311228 T2451 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 311230 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 311230 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 311231 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 311231 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311231 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311231 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 311231 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311231 T2451 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311231 T2451 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 311232 T2451 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311233 T2451 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311233 T2451 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311234 T2451 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311236 T2451 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 311236 T2451 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 311236 T2451 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 311237 T2451 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
   [junit4]   2> 311238 T2451 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 311238 T2451 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 311238 T2451 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=73.474609375, floorSegmentMB=0.2216796875, forceMergeDeletesPctAllowed=11.675276646635503, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21491526645436299
   [junit4]   2> 311239 T2451 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@b183833 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@598b5aa2),segFN=segments_1,generation=1}
   [junit4]   2> 311239 T2451 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 311239 T2451 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@5d110eb2[collection1] main
   [junit4]   2> 311239 T2451 c:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/conf
   [junit4]   2> 311239 T2451 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/conf}
   [junit4]   2> 311239 T2451 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001/./collection1/conf
   [junit4]   2> 311239 T2451 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 311240 T2451 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 311240 T2452 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d110eb2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 311240 T2451 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 311240 T2426 oass.SolrDispatchFilter.init user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 311241 T2426 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 311241 T2426 oas.SolrTestCaseHS$SolrInstance.start ===> Started solr server port=40760 home=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s1-001
   [junit4]   2> 311252 T2426 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 311253 T2426 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47600
   [junit4]   2> 311253 T2426 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=47600}
   [junit4]   2> 311253 T2426 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 311254 T2426 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/'
   [junit4]   2> 311261 T2426 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/solr.xml
   [junit4]   2> 311273 T2426 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/.
   [junit4]   2> 311274 T2426 oasc.CoreContainer.<init> New CoreContainer 270854650
   [junit4]   2> 311274 T2426 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/]
   [junit4]   2> 311274 T2426 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/lib
   [junit4]   2> 311274 T2426 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/lib).
   [junit4]   2> 311279 T2426 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 311279 T2426 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 311279 T2426 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 311279 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 311280 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
   [junit4]   2> 311280 T2426 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 311280 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 311280 T2426 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 311280 T2426 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 311280 T2426 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 311280 T2426 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
   [junit4]   2> 311281 T2426 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 311281 T2426 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 311282 T2426 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 311282 T2426 oasc.CoreContainer.load Node Name: 127.0.0.1
   [junit4]   2> 311282 T2426 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/.
   [junit4]   2> 311283 T2426 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1, loadOnStartup=true, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/, schema=schema_latest.xml, transient=false, dataDir=data/}
   [junit4]   2> 311283 T2426 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/
   [junit4]   2> 311283 T2426 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 311284 T2463 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/'
   [junit4]   2> 311297 T2463 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
   [junit4]   2> 311299 T2463 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
   [junit4]   2> 311304 T2463 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 311304 T2463 oass.IndexSchema.readSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/conf/schema_latest.xml
   [junit4]   2> 311307 T2463 oass.IndexSchema.readSchema [collection1] Schema name=example
   [junit4]   2> 311336 T2463 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
   [junit4]   2> 311338 T2463 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 311341 T2463 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 311342 T2463 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 311343 T2463 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/
   [junit4]   2> 311343 T2463 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 311343 T2463 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/, dataDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data/
   [junit4]   2> 311343 T2463 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5755d26d
   [junit4]   2> 311344 T2463 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data
   [junit4]   2> 311344 T2463 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data/index/
   [junit4]   2> 311344 T2463 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 311345 T2463 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data/index
   [junit4]   2> 311345 T2463 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 311345 T2463 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@56dd9c3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eea8b00),segFN=segments_1,generation=1}
   [junit4]   2> 311345 T2463 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 311348 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 311348 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 311348 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 311348 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311348 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 311348 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 311349 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311349 T2463 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 311349 T2463 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 311349 T2463 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311350 T2463 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311350 T2463 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311351 T2463 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 311353 T2463 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 311354 T2463 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 311354 T2463 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 311354 T2463 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
   [junit4]   2> 311354 T2463 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 311355 T2463 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 311355 T2463 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=73.474609375, floorSegmentMB=0.2216796875, forceMergeDeletesPctAllowed=11.675276646635503, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21491526645436299
   [junit4]   2> 311355 T2463 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@56dd9c3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eea8b00),segFN=segments_1,generation=1}
   [junit4]   2> 311355 T2463 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 311356 T2463 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@15143fc0[collection1] main
   [junit4]   2> 311356 T2463 c:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/conf
   [junit4]   2> 311356 T2463 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/conf}
   [junit4]   2> 311356 T2463 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/conf
   [junit4]   2> 311356 T2463 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 311356 T2463 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 311357 T2464 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15143fc0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 311357 T2463 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 311357 T2426 oass.SolrDispatchFilter.init user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 311357 T2426 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 311358 T2426 oas.SolrTestCaseHS$SolrInstance.start ===> Started solr server port=47600 home=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001
   [junit4]   2> ASYNC  NEW_CORE C515 name=collection1 org.apache.solr.core.SolrCore@670f60f3
   [junit4]   2> 311363 T2437 c:collection1 C515 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498211514376519680)} 0 2
   [junit4]   2> ASYNC  NEW_CORE C516 name=collection1 org.apache.solr.core.SolrCore@48854f28
   [junit4]   2> 311366 T2449 c:collection1 C516 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498211514379665408)} 0 2
   [junit4]   2> ASYNC  NEW_CORE C517 name=collection1 org.apache.solr.core.SolrCore@3b6b5f76
   [junit4]   2> 311368 T2461 c:collection1 C517 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498211514381762560)} 0 2
   [junit4]   2> 311372 T2450 c:collection1 C516 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1498211514385956864)]} 0 2
   [junit4]   2> 311375 T2449 c:collection1 C516 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1498211514390151168)]} 0 1
   [junit4]   2> 311378 T2438 c:collection1 C515 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3 (1498211514393296896)]} 0 1
   [junit4]   2> 311380 T2437 c:collection1 C515 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311382 T2437 c:collection1 C515 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_2,generation=2}
   [junit4]   2> 311382 T2437 c:collection1 C515 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 311383 T2437 c:collection1 C515 oass.SolrIndexSearcher.<init> Opening Searcher@639872a0[collection1] main
   [junit4]   2> 311384 T2437 c:collection1 C515 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311384 T2440 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@639872a0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c1)))}
   [junit4]   2> 311384 T2437 c:collection1 C515 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 5
   [junit4]   2> 311386 T2450 c:collection1 C516 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311389 T2450 c:collection1 C516 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@b183833 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@598b5aa2),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@b183833 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@598b5aa2),segFN=segments_2,generation=2}
   [junit4]   2> 311389 T2450 c:collection1 C516 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 311391 T2450 c:collection1 C516 oass.SolrIndexSearcher.<init> Opening Searcher@62a515d8[collection1] main
   [junit4]   2> 311391 T2450 c:collection1 C516 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311392 T2452 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62a515d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c2)))}
   [junit4]   2> 311392 T2450 c:collection1 C516 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 6
   [junit4]   2> 311394 T2462 c:collection1 C517 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311394 T2462 c:collection1 C517 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 311394 T2462 c:collection1 C517 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 311395 T2462 c:collection1 C517 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311396 T2462 c:collection1 C517 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 2
   [junit4]   2> 311399 T2449 c:collection1 C516 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4 (1498211514415316992)]} 0 1
   [junit4]   2> 311402 T2438 c:collection1 C515 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5 (1498211514418462720)]} 0 1
   [junit4]   2> 311404 T2437 c:collection1 C515 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311407 T2437 c:collection1 C515 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_3,generation=3}
   [junit4]   2> 311407 T2437 c:collection1 C515 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 311408 T2437 c:collection1 C515 oass.SolrIndexSearcher.<init> Opening Searcher@6fd408d5[collection1] main
   [junit4]   2> 311409 T2440 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6fd408d5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c1) Uninverting(_1(5.2.0):c1)))}
   [junit4]   2> 311410 T2437 c:collection1 C515 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311410 T2437 c:collection1 C515 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 7
   [junit4]   2> 311412 T2450 c:collection1 C516 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311415 T2450 c:collection1 C516 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@b183833 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@598b5aa2),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@b183833 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@598b5aa2),segFN=segments_3,generation=3}
   [junit4]   2> 311415 T2450 c:collection1 C516 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 311416 T2450 c:collection1 C516 oass.SolrIndexSearcher.<init> Opening Searcher@1b54cdba[collection1] main
   [junit4]   2> 311417 T2452 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b54cdba[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c2) Uninverting(_1(5.2.0):c1)))}
   [junit4]   2> 311418 T2450 c:collection1 C516 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311418 T2450 c:collection1 C516 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 6
   [junit4]   2> 311421 T2461 c:collection1 C517 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311421 T2461 c:collection1 C517 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 311421 T2461 c:collection1 C517 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 311421 T2461 c:collection1 C517 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311422 T2461 c:collection1 C517 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 1
   [junit4]   2> 311426 T2438 c:collection1 C515 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6 (1498211514442579968)]} 0 2
   [junit4]   2> 311429 T2437 c:collection1 C515 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311433 T2437 c:collection1 C515 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@2a5e62e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30fc992c),segFN=segments_4,generation=4}
   [junit4]   2> 311434 T2437 c:collection1 C515 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 311434 T2437 c:collection1 C515 oass.SolrIndexSearcher.<init> Opening Searcher@19412906[collection1] main
   [junit4]   2> 311436 T2440 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19412906[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c1) Uninverting(_1(5.2.0):c1) Uninverting(_2(5.2.0):c1)))}
   [junit4]   2> 311437 T2437 c:collection1 C515 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311437 T2437 c:collection1 C515 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 9
   [junit4]   2> 311439 T2449 c:collection1 C516 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311440 T2449 c:collection1 C516 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 311440 T2449 c:collection1 C516 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 311440 T2449 c:collection1 C516 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311440 T2449 c:collection1 C516 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 1
   [junit4]   2> 311443 T2462 c:collection1 C517 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 311443 T2462 c:collection1 C517 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 311443 T2462 c:collection1 C517 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 311444 T2462 c:collection1 C517 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 311444 T2462 c:collection1 C517 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 2
   [junit4]   2> 311482 T2461 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:47600/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={catA:{query:{q:'cat_s:A'}},++catA2:{query:{query:'cat_s:A'}},++catA3:{query:'cat_s:A'}++++}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841891&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=0 status=0 QTime=6 
   [junit4]   2> 311484 T2437 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:51126/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={catA:{query:{q:'cat_s:A'}},++catA2:{query:{query:'cat_s:A'}},++catA3:{query:'cat_s:A'}++++}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841891&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=9 
   [junit4]   2> 311484 T2450 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:40760/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={catA:{query:{q:'cat_s:A'}},++catA2:{query:{query:'cat_s:A'}},++catA3:{query:'cat_s:A'}++++}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841891&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=1 
   [junit4]   2> 311486 T2438 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&indent=true&noexist=noexist_s&val_b=val_b&cat_s=cat_s&rows=0&version=2.2&num_d=num_d&shards=127.0.0.1:51126/solr/collection1,127.0.0.1:40760/solr/collection1,127.0.0.1:47600/solr/collection1&q=*:*&json.facet={catA:{query:{q:'${cat_s}:A'}},++catA2:{query:{query:'${cat_s}:A'}},++catA3:{query:'${cat_s}:A'}++++}&num_i=num_i&sparse_s=sparse_s&where_s=where_s&wt=json&multi_ss=multi_ss} hits=6 status=0 QTime=39 
   [junit4]   2> 311497 T2462 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:47600/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:'where_s:NJ'},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841936&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=0 status=0 QTime=1 
   [junit4]   2> 311497 T2437 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:51126/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:'where_s:NJ'},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841936&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=1 
   [junit4]   2> 311497 T2450 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:40760/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:'where_s:NJ'},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841936&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=1 
   [junit4]   2> 311498 T2449 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&indent=true&noexist=noexist_s&val_b=val_b&cat_s=cat_s&rows=0&version=2.2&num_d=num_d&shards=127.0.0.1:51126/solr/collection1,127.0.0.1:40760/solr/collection1,127.0.0.1:47600/solr/collection1&q=*:*&json.facet={+catB:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&where_s=where_s&wt=json&multi_ss=multi_ss} hits=6 status=0 QTime=7 
   [junit4]   2> 311504 T2461 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:47600/solr/collection1&rows=0&version=2&num_d=num_d&q=id:(2+3)&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:'where_s:NJ'},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841945&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=0 status=0 QTime=1 
   [junit4]   2> 311505 T2437 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:51126/solr/collection1&rows=0&version=2&num_d=num_d&q=id:(2+3)&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:'where_s:NJ'},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841945&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=1 status=0 QTime=1 
   [junit4]   2> 311505 T2450 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:40760/solr/collection1&rows=0&version=2&num_d=num_d&q=id:(2+3)&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:'where_s:NJ'},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841945&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=1 status=0 QTime=1 
   [junit4]   2> 311506 T2438 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&indent=true&noexist=noexist_s&val_b=val_b&cat_s=cat_s&rows=0&version=2.2&num_d=num_d&shards=127.0.0.1:51126/solr/collection1,127.0.0.1:40760/solr/collection1,127.0.0.1:47600/solr/collection1&q=id:(2+3)&json.facet={+catB:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&where_s=where_s&wt=json&multi_ss=multi_ss} hits=2 status=0 QTime=5 
   [junit4]   2> 311524 T2437 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:51126/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:{q:'where_s:NJ'}},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841953&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=2 
   [junit4]   2> 311524 T2461 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:47600/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:{q:'where_s:NJ'}},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841953&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=0 status=0 QTime=1 
   [junit4]   2> 311525 T2450 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:40760/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={+catB:{query:{q:'cat_s:B',+facet:{nj:{query:{q:'where_s:NJ'}},+ny:{query:'where_s:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841953&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=0 
   [junit4]   2> 311526 T2462 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&indent=true&noexist=noexist_s&val_b=val_b&cat_s=cat_s&rows=0&version=2.2&num_d=num_d&shards=127.0.0.1:51126/solr/collection1,127.0.0.1:40760/solr/collection1,127.0.0.1:47600/solr/collection1&q=*:*&json.facet={+catB:{query:{q:'${cat_s}:B',+facet:{nj:{query:{q:'${where_s}:NJ'}},+ny:{query:'${where_s}:NY'}}+}}}&num_i=num_i&sparse_s=sparse_s&where_s=where_s&wt=json&multi_ss=multi_ss} hits=6 status=0 QTime=17 
   [junit4]   2> 311538 T2461 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:47600/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={c1:{field:'cat_s'},+c2:{field:{field:'cat_s'}},+c3:{terms:{field:'cat_s'}}++}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841972&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=0 status=0 QTime=1 
   [junit4]   2> 311549 T2450 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:40760/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={c1:{field:'cat_s'},+c2:{field:{field:'cat_s'}},+c3:{terms:{field:'cat_s'}}++}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841972&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=9 
   [junit4]   2> 311549 T2438 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:51126/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={c1:{field:'cat_s'},+c2:{field:{field:'cat_s'}},+c3:{terms:{field:'cat_s'}}++}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841972&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=11 
   [junit4]   2> 311552 T2449 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&indent=true&noexist=noexist_s&val_b=val_b&cat_s=cat_s&rows=0&version=2.2&num_d=num_d&shards=127.0.0.1:51126/solr/collection1,127.0.0.1:40760/solr/collection1,127.0.0.1:47600/solr/collection1&q=*:*&json.facet={c1:{field:'${cat_s}'},+c2:{field:{field:'${cat_s}'}},+c3:{terms:{field:'${cat_s}'}}++}&num_i=num_i&sparse_s=sparse_s&where_s=where_s&wt=json&multi_ss=multi_ss} hits=6 status=0 QTime=24 
   [junit4]   2> 311557 T2462 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:47600/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={f1:{terms:{field:'cat_s',+mincount:3}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841999&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=0 status=0 QTime=1 
   [junit4]   2> 311557 T2449 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:40760/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={f1:{terms:{field:'cat_s',+mincount:3}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841999&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=1 
   [junit4]   2> 311557 T2438 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:51126/solr/collection1&rows=0&version=2&num_d=num_d&q=*:*&json.facet={f1:{terms:{field:'cat_s',+mincount:3}}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805841999&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=3 status=0 QTime=1 
   [junit4]   2> 311558 T2437 c:collection1 C515 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&indent=true&noexist=noexist_s&val_b=val_b&cat_s=cat_s&rows=0&version=2.2&num_d=num_d&shards=127.0.0.1:51126/solr/collection1,127.0.0.1:40760/solr/collection1,127.0.0.1:47600/solr/collection1&q=*:*&json.facet={f1:{terms:{field:'${cat_s}',+mincount:3}}}&num_i=num_i&sparse_s=sparse_s&where_s=where_s&wt=json&multi_ss=multi_ss} hits=6 status=0 QTime=3 
   [junit4]   2> 311562 T2461 c:collection1 C517 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&val_b=val_b&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:47600/solr/collection1&rows=0&version=2&num_d=num_d&q=id:1&json.facet={f1:{terms:'cat_s'}}&num_i=num_i&sparse_s=sparse_s&NOW=1428805842004&where_s=where_s&isShard=true&wt=javabin&multi_ss=multi_ss} hits=0 status=0 QTime=0 
   [junit4]   2> 311563 T2475 c:collection1 C516 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={super_s=super_s&distrib=false&_facet_={}

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

C-001/s1-001/./collection1/data/index
   [junit4]   2> 314205 T2426 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 314418 T2426 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=270854650
   [junit4]   2> 314418 T2426 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3b6b5f76
   [junit4]   2> 314419 T2426 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=15,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=5,cumulative_errors=0,transaction_logs_total_size=335,transaction_logs_total_number=5}
   [junit4]   2> 314419 T2426 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 314419 T2426 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 314420 T2426 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 314420 T2426 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 314427 T2426 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 314428 T2426 oasc.CachingDirectoryFactory.closeCacheValue looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data;done=false>>]
   [junit4]   2> 314428 T2426 oasc.CachingDirectoryFactory.close Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data
   [junit4]   2> 314428 T2426 oasc.CachingDirectoryFactory.closeCacheValue looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data/index;done=false>>]
   [junit4]   2> 314428 T2426 oasc.CachingDirectoryFactory.close Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/s2-001/./collection1/data/index
   [junit4]   2> 314429 T2426 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 314609 T2426 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 314609 T2426 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=347156364
   [junit4]   2> 314609 T2426 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@69ec3dcf
   [junit4]   2> 314610 T2426 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=148,cumulative_deletesById=0,cumulative_deletesByQuery=7,cumulative_errors=0,transaction_logs_total_size=2409,transaction_logs_total_number=10}
   [junit4]   2> 314610 T2426 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 314610 T2426 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 314610 T2426 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 314610 T2426 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 314613 T2426 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 314613 T2426 oasc.CachingDirectoryFactory.closeCacheValue looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 314613 T2426 oasc.CachingDirectoryFactory.close Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001/index
   [junit4]   2> 314613 T2426 oasc.CachingDirectoryFactory.closeCacheValue looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001;done=false>>]
   [junit4]   2> 314613 T2426 oasc.CachingDirectoryFactory.close Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001/init-core-data-001
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets B566B8DDFE30CA8C-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): {num_dd=PostingsFormat(name=Memory doPackFST= true), super_s=PostingsFormat(name=Asserting), cat_sds=PostingsFormat(name=Asserting), where_sds=FSTOrd50, super_sds=FSTOrd50, cat_ss=PostingsFormat(name=Memory doPackFST= true), sparse_ss=PostingsFormat(name=Asserting), super_sd=PostingsFormat(name=Memory doPackFST= true), where_s=PostingsFormat(name=Memory doPackFST= true), sparse_sd=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=Memory doPackFST= true), multi_ss=FSTOrd50, sparse_sds=PostingsFormat(name=Memory doPackFST= true), cat_sd=FSTOrd50, super_ss=PostingsFormat(name=LuceneVarGapFixedInterval), val_b=PostingsFormat(name=LuceneVarGapFixedInterval), multi_sds=PostingsFormat(name=LuceneVarGapFixedInterval), cat_s=FSTOrd50, where_sd=PostingsFormat(name=Asserting), make_s=FSTOrd50, num_id=PostingsFormat(name=LuceneVarGapFixedInterval), num_d=PostingsFormat(name=Memory doPackFST= true), num_i=PostingsFormat(name=LuceneVarGapFixedInterval), _version_=FSTOrd50, cost_f=PostingsFormat(name=LuceneVarGapFixedInterval), sparse_s=PostingsFormat(name=LuceneVarGapFixedInterval), where_ss=PostingsFormat(name=LuceneVarGapFixedInterval), model_s=PostingsFormat(name=Asserting)}, docValues:{super_sds=DocValuesFormat(name=Asserting), num_dd=DocValuesFormat(name=Direct), sparse_sds=DocValuesFormat(name=Direct), super_sd=DocValuesFormat(name=Direct), cat_sd=DocValuesFormat(name=Asserting), cat_sds=DocValuesFormat(name=Lucene50), multi_sds=DocValuesFormat(name=Memory), where_sds=DocValuesFormat(name=Asserting), where_sd=DocValuesFormat(name=Lucene50), sparse_sd=DocValuesFormat(name=Memory), num_id=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=fi_FI, timezone=Asia/Ulan_Bator
   [junit4]   2> NOTE: Linux 3.13.0-49-generic amd64/Oracle Corporation 1.8.0_60-ea (64-bit)/cpus=12,threads=1,free=254884152,total=509083648
   [junit4]   2> NOTE: All tests run in this JVM: [SuggesterTSTTest, TestRawResponseWriter, CopyFieldTest, TestSolrDynamicMBean, TestCopyFieldCollectionResource, DistanceFunctionTest, MultiThreadedOCPTest, TestOmitPositions, TestMaxScoreQueryParser, TestRestManager, DistributedMLTComponentTest, StatelessScriptUpdateProcessorFactoryTest, OpenCloseCoreStressTest, SolrCmdDistributorTest, CustomCollectionTest, ZkSolrClientTest, TestUniqueKeyFieldResource, ShowFileRequestHandlerTest, DeleteLastCustomShardedReplicaTest, BadComponentTest, DisMaxRequestHandlerTest, SolrPluginUtilsTest, ExternalCollectionsTest, TestHashQParserPlugin, RollingRestartTest, TestFiltering, TestFastLRUCache, NoCacheHeaderTest, TestManagedStopFilterFactory, TestValueSourceCache, HdfsUnloadDistributedZkTest, OverriddenZkACLAndCredentialsProvidersTest, TestFieldTypeResource, AlternateDirectoryTest, TestSolrXml, TestJsonFacets]
   [junit4] Completed [127/482] on J1 in 3.78s, 4 tests, 1 error <<< FAILURES!

[...truncated 1092 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:536: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:484: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:229: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:510: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1434: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:991: There were test failures: 482 suites, 1914 tests, 1 error, 49 ignored (21 assumptions)

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