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/03/24 04:56:14 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_40) - Build #
4584 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4584/
Java: 32bit/jdk1.8.0_40 -server -XX:+UseParallelGC
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([9CA36F0AA5854E2F:7D7C6A9689CB024C]: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:151)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10558 lines...]
[junit4] Suite: org.apache.solr.search.facet.TestJsonFacets
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001
[junit4] 2> 2110954 T12268 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 2110954 T12268 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 2110955 T12268 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2110956 T12268 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2110992 T12268 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2110995 T12268 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 2111000 T12268 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 2111000 T12268 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema_latest.xml
[junit4] 2> 2111004 T12268 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 2111064 T12268 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
[junit4] 2> 2111067 T12268 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2111075 T12268 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2111077 T12268 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2111080 T12268 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2111080 T12268 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr
[junit4] 2> 2111080 T12268 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\'
[junit4] 2> 2111097 T12268 oasc.CoreContainer.<init> New CoreContainer 16112191
[junit4] 2> 2111097 T12268 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\]
[junit4] 2> 2111097 T12268 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib
[junit4] 2> 2111097 T12268 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib).
[junit4] 2> 2111103 T12268 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 600000
[junit4] 2> 2111104 T12268 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 2111104 T12268 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 60000
[junit4] 2> 2111104 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2111104 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 2111104 T12268 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2111104 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2111105 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2111105 T12268 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2111105 T12268 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2111105 T12268 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 2111105 T12268 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 2111105 T12268 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2111105 T12268 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2111106 T12268 oasc.CoreContainer.load Node Name: testNode
[junit4] 2> 2111128 T12268 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, transient=false, schema=schema_latest.xml, loadOnStartup=true, instanceDir=collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001, shard=shard1}
[junit4] 2> 2111130 T12269 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 2111130 T12269 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2111131 T12269 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2111145 T12269 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2111148 T12269 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 2111211 T12269 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 2111212 T12269 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema_latest.xml
[junit4] 2> 2111215 T12269 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 2111248 T12269 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
[junit4] 2> 2111250 T12269 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2111255 T12269 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2111257 T12269 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2111259 T12269 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 2111259 T12269 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2111259 T12269 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\
[junit4] 2> 2111259 T12269 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6ad4b
[junit4] 2> 2111260 T12269 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\
[junit4] 2> 2111260 T12269 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\index/
[junit4] 2> 2111260 T12269 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 2111260 T12269 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\index
[junit4] 2> 2111261 T12269 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3678604772859012]
[junit4] 2> 2111261 T12269 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_1,generation=1}
[junit4] 2> 2111261 T12269 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2111263 T12269 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2111264 T12269 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2111264 T12269 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2111264 T12269 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2111264 T12269 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2111264 T12269 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2111265 T12269 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2111265 T12269 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2111265 T12269 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2111268 T12269 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111270 T12269 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111271 T12269 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111271 T12269 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111277 T12269 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> 2111278 T12269 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2111285 T12269 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2111285 T12269 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 2111286 T12269 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2111286 T12269 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2111287 T12269 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=32.39453125, floorSegmentMB=0.271484375, forceMergeDeletesPctAllowed=0.6918341983918308, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3501185733848854
[junit4] 2> 2111287 T12269 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_1,generation=1}
[junit4] 2> 2111287 T12269 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2111287 T12269 oass.SolrIndexSearcher.<init> Opening Searcher@2dd61b[collection1] main
[junit4] 2> 2111288 T12269 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 2111288 T12269 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 2111288 T12269 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 2111288 T12269 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2111289 T12269 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2111289 T12270 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2dd61b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2111289 T12269 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2111291 T12268 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 2111295 T12268 oas.SolrTestCaseJ4.setUp ###Starting testComplex
[junit4] 2> ASYNC NEW_CORE C2470 name=collection1 org.apache.solr.core.SolrCore@21bc68
[junit4] 2> 2111316 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1496495439022653440)} 0 20
[junit4] 2> 2111352 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[0 (1496495439047819264)]} 0 32
[junit4] 2> 2111353 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[1 (1496495439082422272)]} 0 0
[junit4] 2> 2111353 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[1 (1496495439082422273)]} 0 0
[junit4] 2> 2111353 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[2 (1496495439082422274)]} 0 0
[junit4] 2> 2111354 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[2 (1496495439083470848)]} 0 0
[junit4] 2> 2111354 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[3 (1496495439083470849)]} 0 0
[junit4] 2> 2111354 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[4 (1496495439083470850)]} 0 0
[junit4] 2> 2111355 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[5 (1496495439084519424)]} 0 0
[junit4] 2> 2111355 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[6 (1496495439084519425)]} 0 0
[junit4] 2> 2111356 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[7 (1496495439085568000)]} 0 0
[junit4] 2> 2111356 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[8 (1496495439085568001)]} 0 0
[junit4] 2> 2111357 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[9 (1496495439086616576)]} 0 1
[junit4] 2> 2111357 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[10 (1496495439086616577)]} 0 0
[junit4] 2> 2111357 T12268 C2470 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2111358 T12268 C2470 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_2,generation=2}
[junit4] 2> 2111361 T12268 C2470 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2111362 T12268 C2470 oass.SolrIndexSearcher.<init> Opening Searcher@1295c22[collection1] main
[junit4] 2> 2111362 T12268 C2470 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2111365 T12270 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1295c22[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c13/2:delGen=1)))}
[junit4] 2> 2111376 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 19
[junit4] 2> 2111378 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[11 (1496495439107588096)]} 0 2
[junit4] 2> 2111378 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[12 (1496495439108636672)]} 0 0
[junit4] 2> 2111379 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[13 (1496495439109685248)]} 0 0
[junit4] 2> 2111379 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[14 (1496495439109685249)]} 0 0
[junit4] 2> 2111379 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[14 (1496495439109685250)]} 0 0
[junit4] 2> 2111379 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[15 (1496495439109685251)]} 0 0
[junit4] 2> 2111379 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[15 (1496495439109685252)]} 0 0
[junit4] 2> 2111388 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[16 (1496495439119122432)]} 0 8
[junit4] 2> 2111389 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[17 (1496495439120171008)]} 0 0
[junit4] 2> 2111389 T12268 C2470 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2111390 T12268 C2470 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_3,generation=3}
[junit4] 2> 2111391 T12268 C2470 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2111391 T12268 C2470 oass.SolrIndexSearcher.<init> Opening Searcher@17e893d[collection1] main
[junit4] 2> 2111391 T12268 C2470 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2111392 T12270 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17e893d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c13/2:delGen=1) Uninverting(_1(6.0.0):c9/2:delGen=1)))}
[junit4] 2> 2111402 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4] 2> 2111419 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[18 (1496495439134851072)]} 0 16
[junit4] 2> 2111420 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[19 (1496495439152676864)]} 0 0
[junit4] 2> 2111420 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[20 (1496495439152676865)]} 0 0
[junit4] 2> 2111420 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[21 (1496495439152676866)]} 0 0
[junit4] 2> 2111420 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[22 (1496495439152676867)]} 0 0
[junit4] 2> 2111421 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[23 (1496495439153725440)]} 0 0
[junit4] 2> 2111421 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[24 (1496495439153725441)]} 0 0
[junit4] 2> 2111421 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[25 (1496495439153725442)]} 0 0
[junit4] 2> 2111422 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[26 (1496495439154774016)]} 0 0
[junit4] 2> 2111422 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[27 (1496495439154774017)]} 0 0
[junit4] 2> 2111423 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[28 (1496495439155822592)]} 0 0
[junit4] 2> 2111425 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[29 (1496495439157919744)]} 0 1
[junit4] 2> 2111425 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[29 (1496495439157919745)]} 0 0
[junit4] 2> 2111426 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[30 (1496495439158968320)]} 0 0
[junit4] 2> 2111426 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[31 (1496495439158968321)]} 0 0
[junit4] 2> 2111427 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[32 (1496495439160016896)]} 0 0
[junit4] 2> 2111427 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[33 (1496495439160016897)]} 0 0
[junit4] 2> 2111427 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[34 (1496495439160016898)]} 0 0
[junit4] 2> 2111428 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[35 (1496495439161065472)]} 0 0
[junit4] 2> 2111428 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[36 (1496495439161065473)]} 0 0
[junit4] 2> 2111429 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[37 (1496495439162114048)]} 0 1
[junit4] 2> 2111429 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[38 (1496495439162114049)]} 0 0
[junit4] 2> 2111430 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[39 (1496495439163162624)]} 0 1
[junit4] 2> 2111432 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[40 (1496495439165259776)]} 0 0
[junit4] 2> 2111433 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[41 (1496495439166308352)]} 0 0
[junit4] 2> 2111434 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[42 (1496495439166308353)]} 0 1
[junit4] 2> 2111441 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[43 (1496495439174696960)]} 0 1
[junit4] 2> 2111442 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[44 (1496495439175745536)]} 0 0
[junit4] 2> 2111442 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[45 (1496495439175745537)]} 0 0
[junit4] 2> 2111443 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[46 (1496495439176794112)]} 0 1
[junit4] 2> 2111443 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[47 (1496495439176794113)]} 0 0
[junit4] 2> 2111443 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[48 (1496495439176794114)]} 0 0
[junit4] 2> 2111444 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[49 (1496495439177842688)]} 0 0
[junit4] 2> 2111444 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[49 (1496495439177842689)]} 0 0
[junit4] 2> 2111444 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[50 (1496495439177842690)]} 0 0
[junit4] 2> 2111445 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[51 (1496495439178891264)]} 0 1
[junit4] 2> 2111445 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[52 (1496495439178891265)]} 0 0
[junit4] 2> 2111445 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[53 (1496495439178891266)]} 0 0
[junit4] 2> 2111445 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[54 (1496495439178891267)]} 0 0
[junit4] 2> 2111445 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[55 (1496495439178891268)]} 0 0
[junit4] 2> 2111445 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[55 (1496495439178891269)]} 0 0
[junit4] 2> 2111446 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[56 (1496495439179939840)]} 0 0
[junit4] 2> 2111446 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[57 (1496495439179939841)]} 0 0
[junit4] 2> 2111449 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[58 (1496495439179939842)]} 0 3
[junit4] 2> 2111449 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[59 (1496495439183085568)]} 0 0
[junit4] 2> 2111450 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[60 (1496495439184134144)]} 0 1
[junit4] 2> 2111450 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[61 (1496495439184134145)]} 0 0
[junit4] 2> 2111450 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[62 (1496495439184134146)]} 0 0
[junit4] 2> 2111450 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[63 (1496495439184134147)]} 0 0
[junit4] 2> 2111450 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[64 (1496495439184134148)]} 0 0
[junit4] 2> 2111450 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[65 (1496495439184134149)]} 0 0
[junit4] 2> 2111451 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[66 (1496495439185182720)]} 0 0
[junit4] 2> 2111451 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[67 (1496495439185182721)]} 0 0
[junit4] 2> 2111451 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[68 (1496495439185182722)]} 0 0
[junit4] 2> 2111452 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[69 (1496495439186231296)]} 0 0
[junit4] 2> 2111453 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[70 (1496495439187279872)]} 0 0
[junit4] 2> 2111453 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[71 (1496495439187279873)]} 0 0
[junit4] 2> 2111453 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[71 (1496495439187279874)]} 0 0
[junit4] 2> 2111454 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[72 (1496495439188328448)]} 0 0
[junit4] 2> 2111454 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[73 (1496495439188328449)]} 0 0
[junit4] 2> 2111454 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[74 (1496495439188328450)]} 0 0
[junit4] 2> 2111455 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[75 (1496495439189377024)]} 0 0
[junit4] 2> 2111455 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[76 (1496495439189377025)]} 0 0
[junit4] 2> 2111455 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[77 (1496495439189377026)]} 0 0
[junit4] 2> 2111455 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[77 (1496495439189377027)]} 0 0
[junit4] 2> 2111455 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[78 (1496495439189377028)]} 0 0
[junit4] 2> 2111455 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[78 (1496495439189377029)]} 0 0
[junit4] 2> 2111456 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[79 (1496495439190425600)]} 0 0
[junit4] 2> 2111456 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[80 (1496495439190425601)]} 0 0
[junit4] 2> 2111456 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[80 (1496495439190425602)]} 0 0
[junit4] 2> 2111457 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[81 (1496495439191474176)]} 0 0
[junit4] 2> 2111457 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[82 (1496495439191474177)]} 0 0
[junit4] 2> 2111457 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[83 (1496495439191474178)]} 0 0
[junit4] 2> 2111458 T12268 C2470 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2111460 T12268 C2470 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_4,generation=4}
[junit4] 2> 2111460 T12268 C2470 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2111468 T12268 C2470 oass.SolrIndexSearcher.<init> Opening Searcher@164ff70[collection1] main
[junit4] 2> 2111471 T12268 C2470 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2111471 T12270 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@164ff70[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c13/2:delGen=1) Uninverting(_1(6.0.0):c9/2:delGen=1) Uninverting(_2(6.0.0):c73/7:delGen=1)))}
[junit4] 2> 2111476 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4] 2> 2111481 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[84 (1496495439212445696)]} 0 4
[junit4] 2> 2111481 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[85 (1496495439216640000)]} 0 0
[junit4] 2> 2111481 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[85 (1496495439216640001)]} 0 0
[junit4] 2> 2111481 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[86 (1496495439216640002)]} 0 0
[junit4] 2> 2111482 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[87 (1496495439217688576)]} 0 0
[junit4] 2> 2111482 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[88 (1496495439217688577)]} 0 0
[junit4] 2> 2111482 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[88 (1496495439217688578)]} 0 0
[junit4] 2> 2111483 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[89 (1496495439218737152)]} 0 0
[junit4] 2> 2111483 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[90 (1496495439218737153)]} 0 0
[junit4] 2> 2111484 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[91 (1496495439218737154)]} 0 1
[junit4] 2> 2111484 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[92 (1496495439219785728)]} 0 0
[junit4] 2> 2111484 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[93 (1496495439219785729)]} 0 0
[junit4] 2> 2111485 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[94 (1496495439220834304)]} 0 0
[junit4] 2> 2111488 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[95 (1496495439223980032)]} 0 0
[junit4] 2> 2111491 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[96 (1496495439225028608)]} 0 2
[junit4] 2> 2111491 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[96 (1496495439227125760)]} 0 0
[junit4] 2> 2111491 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[97 (1496495439227125761)]} 0 0
[junit4] 2> 2111491 T12268 C2470 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2111500 T12268 C2470 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_5,generation=5}
[junit4] 2> 2111501 T12268 C2470 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 2111501 T12268 C2470 oass.SolrIndexSearcher.<init> Opening Searcher@4439c0[collection1] main
[junit4] 2> 2111502 T12268 C2470 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2111502 T12270 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4439c0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c13/2:delGen=1) Uninverting(_1(6.0.0):c9/2:delGen=1) Uninverting(_2(6.0.0):c73/7:delGen=1) Uninverting(_3(6.0.0):c17/3:delGen=1)))}
[junit4] 2> 2111503 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 2111505 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[98 (1496495439240757248)]} 0 1
[junit4] 2> 2111506 T12268 C2470 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2111507 T12268 C2470 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@f4286 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb110),segFN=segments_6,generation=6}
[junit4] 2> 2111507 T12268 C2470 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 2111508 T12268 C2470 oass.SolrIndexSearcher.<init> Opening Searcher@10b908f[collection1] main
[junit4] 2> 2111509 T12268 C2470 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2111509 T12270 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10b908f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c13/2:delGen=1) Uninverting(_1(6.0.0):c9/2:delGen=1) Uninverting(_2(6.0.0):c73/7:delGen=1) Uninverting(_3(6.0.0):c17/3:delGen=1) Uninverting(_4(6.0.0):c1)))}
[junit4] 2> 2111511 T12268 C2470 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 2111560 T12268 C2470 oasc.SolrCore.execute [collection1] webapp=null path=null params={make_s=make_s&model_s=model_s&price_low=11000.0&price_high=100000.0&q=*%3A*&rows=0&fq=%2B%24%7Bmake_s%7D%3Ahonda+%2Bcost_f%3A%5B%24%7Bprice_low%7D+TO+%24%7Bprice_high%7D%5D&json.facet=%7Bmakes%3A%7Bterms%3A%7Bfield%3A%24%7Bmake_s%7D%2C+facet%3A%7Bmodels%3A%7Bterms%3A%7Bfield%3A%24%7Bmodel_s%7D%2C+limit%3A2%2C+mincount%3A0%7D%7D%7D%7D%7D%7D%7D&facet=true&facet.pivot=make_s%2Cmodel_s&facet.limit=2&wt=json&indent=true} hits=6 status=0 QTime=48
[junit4] 2> 2111588 T12268 oas.SolrTestCaseHS.matchJSON ERROR query failed JSON validation. error=mismatch: 'accord'!='a' @ facets/makes/buckets/[0]/models/buckets/[1]/val
[junit4] 2> expected =facets=={count:6, makes:{buckets:[{val:honda, count:6, models:{buckets:[{val:pilot, count:3},{val:accord, count:0}]}}]}}
[junit4] 2> response = {
[junit4] 2> "responseHeader":{
[junit4] 2> "status":0,
[junit4] 2> "QTime":48},
[junit4] 2> "response":{"numFound":6,"start":0,"docs":[]
[junit4] 2> },
[junit4] 2> "facet_counts":{
[junit4] 2> "facet_queries":{},
[junit4] 2> "facet_fields":{},
[junit4] 2> "facet_dates":{},
[junit4] 2> "facet_ranges":{},
[junit4] 2> "facet_intervals":{},
[junit4] 2> "facet_heatmaps":{},
[junit4] 2> "facet_pivot":{
[junit4] 2> "make_s,model_s":[{
[junit4] 2> "field":"make_s",
[junit4] 2> "value":"honda",
[junit4] 2> "count":6,
[junit4] 2> "pivot":[{
[junit4] 2> "field":"model_s",
[junit4] 2> "value":"pilot",
[junit4] 2> "count":3}]}]}},
[junit4] 2> "facets":{
[junit4] 2> "count":6,
[junit4] 2> "makes":{
[junit4] 2> "buckets":[{
[junit4] 2> "val":"honda",
[junit4] 2> "count":6,
[junit4] 2> "models":{
[junit4] 2> "buckets":[{
[junit4] 2> "val":"pilot",
[junit4] 2> "count":3},
[junit4] 2> {
[junit4] 2> "val":"a",
[junit4] 2> "count":0}]}}]}}}
[junit4] 2>
[junit4] 2> 2111588 T12268 oas.SolrTestCaseJ4.tearDown ###Ending testComplex
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestJsonFacets -Dtests.method=testComplex -Dtests.seed=9CA36F0AA5854E2F -Dtests.slow=true -Dtests.locale=nl -Dtests.timezone=Europe/Istanbul -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.30s J0 | TestJsonFacets.testComplex <<<
[junit4] > Throwable #1: java.lang.RuntimeException: mismatch: 'accord'!='a' @ facets/makes/buckets/[0]/models/buckets/[1]/val
[junit4] > at __randomizedtesting.SeedInfo.seed([9CA36F0AA5854E2F:7D7C6A9689CB024C]:0)
[junit4] > at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:160)
[junit4] > at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:142)
[junit4] > at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:288)
[junit4] > at org.apache.solr.search.facet.TestJsonFacets.testComplex(TestJsonFacets.java:151)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2111593 T12268 oas.SolrTestCaseJ4.setUp ###Starting testDistrib
[junit4] 2> 2111662 T12268 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 2111701 T12268 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@163da5f{/solr,null,AVAILABLE}
[junit4] 2> 2111702 T12268 oejs.AbstractConnector.doStart Started ServerConnector@46451a{HTTP/1.1}{127.0.0.1:56793}
[junit4] 2> 2111702 T12268 oejs.Server.doStart Started @2117704ms
[junit4] 2> 2111703 T12268 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=56793}
[junit4] 2> 2111703 T12268 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 2111703 T12268 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\'
[junit4] 2> 2111726 T12268 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\solr.xml
[junit4] 2> 2111731 T12268 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.
[junit4] 2> 2111732 T12268 oasc.CoreContainer.<init> New CoreContainer 15932357
[junit4] 2> 2111732 T12268 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\]
[junit4] 2> 2111732 T12268 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\lib
[junit4] 2> 2111733 T12268 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\lib).
[junit4] 2> 2111740 T12268 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2111740 T12268 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2111740 T12268 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2111741 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2111741 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 2111742 T12268 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2111742 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2111742 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2111742 T12268 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2111743 T12268 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2111743 T12268 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 2111743 T12268 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2111743 T12268 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2111744 T12268 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2111744 T12268 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2111745 T12268 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.
[junit4] 2> 2111747 T12268 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\, schema=schema_latest.xml, transient=false, dataDir=data\}
[junit4] 2> 2111747 T12268 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\
[junit4] 2> 2111748 T12268 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2111788 T12283 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\'
[junit4] 2> 2111807 T12283 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2111808 T12283 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 2111814 T12283 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 2111815 T12283 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\conf\schema_latest.xml
[junit4] 2> 2111818 T12283 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 2111865 T12283 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
[junit4] 2> 2111867 T12283 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2111874 T12283 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2111876 T12283 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2111877 T12283 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\
[junit4] 2> 2111877 T12283 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2111877 T12283 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\data\
[junit4] 2> 2111877 T12283 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6ad4b
[junit4] 2> 2111892 T12283 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\data\
[junit4] 2> 2111892 T12283 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\data\index/
[junit4] 2> 2111892 T12283 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2111893 T12283 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\data\index
[junit4] 2> 2111893 T12283 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3678604772859012]
[junit4] 2> 2111893 T12283 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@79c4db lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14d338d),segFN=segments_1,generation=1}
[junit4] 2> 2111894 T12283 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2111919 T12283 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2111920 T12283 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2111920 T12283 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2111921 T12283 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2111921 T12283 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2111921 T12283 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2111921 T12283 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2111921 T12283 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2111921 T12283 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2111962 T12283 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111966 T12283 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111968 T12283 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111993 T12283 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2111997 T12283 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> 2111998 T12283 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2112029 T12283 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2112029 T12283 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 2112029 T12283 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2112030 T12283 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2112031 T12283 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=32.39453125, floorSegmentMB=0.271484375, forceMergeDeletesPctAllowed=0.6918341983918308, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3501185733848854
[junit4] 2> 2112031 T12283 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@79c4db lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14d338d),segFN=segments_1,generation=1}
[junit4] 2> 2112031 T12283 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2112032 T12283 oass.SolrIndexSearcher.<init> Opening Searcher@182b2d4[collection1] main
[junit4] 2> 2112032 T12283 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\conf
[junit4] 2> 2112032 T12283 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\conf}
[junit4] 2> 2112032 T12283 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001\.\collection1\conf
[junit4] 2> 2112034 T12283 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2112034 T12283 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2112034 T12284 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@182b2d4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2112035 T12283 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2112068 T12268 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2112068 T12268 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2112069 T12268 oas.SolrTestCaseHS$SolrInstance.start ===> Started solr server port=56793 home=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s0-001
[junit4] 2> 2112115 T12268 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 2112129 T12268 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1e95499{/solr,null,AVAILABLE}
[junit4] 2> 2112129 T12268 oejs.AbstractConnector.doStart Started ServerConnector@194c130{HTTP/1.1}{127.0.0.1:56804}
[junit4] 2> 2112130 T12268 oejs.Server.doStart Started @2118158ms
[junit4] 2> 2112130 T12268 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=56804}
[junit4] 2> 2112130 T12268 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 2112130 T12268 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\'
[junit4] 2> 2112144 T12268 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\solr.xml
[junit4] 2> 2112147 T12268 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.
[junit4] 2> 2112148 T12268 oasc.CoreContainer.<init> New CoreContainer 6691274
[junit4] 2> 2112148 T12268 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\]
[junit4] 2> 2112148 T12268 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\lib
[junit4] 2> 2112148 T12268 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\lib).
[junit4] 2> 2112155 T12268 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2112156 T12268 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2112156 T12268 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2112157 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2112157 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 2112157 T12268 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2112158 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2112158 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2112158 T12268 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2112158 T12268 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2112158 T12268 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 2112159 T12268 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2112159 T12268 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2112160 T12268 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2112160 T12268 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2112161 T12268 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.
[junit4] 2> 2112163 T12268 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\, schema=schema_latest.xml, transient=false, dataDir=data\}
[junit4] 2> 2112163 T12268 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\
[junit4] 2> 2112164 T12268 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2112168 T12297 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\'
[junit4] 2> 2112185 T12297 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2112188 T12297 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 2112197 T12297 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 2112198 T12297 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\conf\schema_latest.xml
[junit4] 2> 2112204 T12297 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 2112244 T12297 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
[junit4] 2> 2112246 T12297 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2112253 T12297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2112255 T12297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2112256 T12297 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\
[junit4] 2> 2112257 T12297 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2112257 T12297 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\data\
[junit4] 2> 2112257 T12297 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6ad4b
[junit4] 2> 2112258 T12297 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\data\
[junit4] 2> 2112261 T12297 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\data\index/
[junit4] 2> 2112262 T12297 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2112263 T12297 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\data\index
[junit4] 2> 2112264 T12297 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3678604772859012]
[junit4] 2> 2112264 T12297 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1f5d50a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cb692),segFN=segments_1,generation=1}
[junit4] 2> 2112265 T12297 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2112291 T12297 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2112292 T12297 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2112292 T12297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2112292 T12297 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2112292 T12297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2112293 T12297 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2112293 T12297 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2112293 T12297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2112293 T12297 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2112295 T12297 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2112296 T12297 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2112298 T12297 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2112299 T12297 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2112301 T12297 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> 2112302 T12297 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2112304 T12297 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2112305 T12297 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 2112306 T12297 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2112306 T12297 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2112306 T12297 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=32.39453125, floorSegmentMB=0.271484375, forceMergeDeletesPctAllowed=0.6918341983918308, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3501185733848854
[junit4] 2> 2112307 T12297 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1f5d50a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cb692),segFN=segments_1,generation=1}
[junit4] 2> 2112307 T12297 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2112307 T12297 oass.SolrIndexSearcher.<init> Opening Searcher@b0a6a9[collection1] main
[junit4] 2> 2112307 T12297 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\conf
[junit4] 2> 2112308 T12297 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\conf}
[junit4] 2> 2112308 T12297 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001\.\collection1\conf
[junit4] 2> 2112308 T12297 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 2112308 T12297 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2112309 T12297 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2112313 T12298 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b0a6a9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2112314 T12268 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2112314 T12268 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2112314 T12268 oas.SolrTestCaseHS$SolrInstance.start ===> Started solr server port=56804 home=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s1-001
[junit4] 2> 2112386 T12268 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 2112392 T12268 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1e5b33e{/solr,null,AVAILABLE}
[junit4] 2> 2112407 T12268 oejs.AbstractConnector.doStart Started ServerConnector@1636aa8{HTTP/1.1}{127.0.0.1:56814}
[junit4] 2> 2112408 T12268 oejs.Server.doStart Started @2118453ms
[junit4] 2> 2112408 T12268 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {schema=schema_latest.xml, solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=56814}
[junit4] 2> 2112409 T12268 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 2112409 T12268 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\'
[junit4] 2> 2112431 T12268 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\solr.xml
[junit4] 2> 2112438 T12268 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.
[junit4] 2> 2112438 T12268 oasc.CoreContainer.<init> New CoreContainer 20215137
[junit4] 2> 2112438 T12268 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\]
[junit4] 2> 2112439 T12268 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\lib
[junit4] 2> 2112439 T12268 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\lib).
[junit4] 2> 2112447 T12268 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2112447 T12268 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2112447 T12268 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2112447 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2112448 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 2112448 T12268 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2112449 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2112449 T12268 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2112449 T12268 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2112449 T12268 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2112450 T12268 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 2112450 T12268 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2112450 T12268 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2112450 T12268 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2112451 T12268 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2112473 T12268 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.
[junit4] 2> 2112474 T12268 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-tlog.xml, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1, loadOnStartup=true, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\, schema=schema_latest.xml, transient=false, dataDir=data\}
[junit4] 2> 2112474 T12268 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\
[junit4] 2> 2112476 T12268 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 2112478 T12311 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\'
[junit4] 2> 2112499 T12311 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 2112502 T12311 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 2112507 T12311 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 2112508 T12311 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\conf\schema_latest.xml
[junit4] 2> 2112510 T12311 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 2112545 T12311 oass.AbstractSpatialFieldType.init WARN units parameter is deprecated, please use distanceUnits instead for field types with class SpatialRecursivePrefixTreeFieldType
[junit4] 2> 2112546 T12311 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2112553 T12311 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2112554 T12311 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2112556 T12311 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\
[junit4] 2> 2112556 T12311 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2112557 T12311 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\data\
[junit4] 2> 2112557 T12311 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6ad4b
[junit4] 2> 2112557 T12311 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\data\
[junit4] 2> 2112558 T12311 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\data\index/
[junit4] 2> 2112558 T12311 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2112558 T12311 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\s2-001\.\collection1\data\index
[junit4] 2> 2112558 T12311 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854
[...truncated too long message...]
orkspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\index;done=false>>]
[junit4] 2> 2117608 T12268 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\index
[junit4] 2> 2117609 T12268 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\;done=false>>]
[junit4] 2> 2117609 T12268 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001\init-core-data-001\
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.facet.TestJsonFacets 9CA36F0AA5854E2F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {super_s=PostingsFormat(name=MockRandom), super_ss=PostingsFormat(name=LuceneVarGapDocFreqInterval), val_b=PostingsFormat(name=LuceneVarGapDocFreqInterval), cat_s=BlockTreeOrds(blocksize=128), make_s=BlockTreeOrds(blocksize=128), num_d=PostingsFormat(name=LuceneFixedGap), num_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=BlockTreeOrds(blocksize=128), cost_f=PostingsFormat(name=LuceneVarGapDocFreqInterval), cat_ss=PostingsFormat(name=LuceneFixedGap), sparse_ss=PostingsFormat(name=MockRandom), sparse_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), where_s=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=LuceneFixedGap), where_ss=PostingsFormat(name=LuceneVarGapDocFreqInterval), model_s=PostingsFormat(name=MockRandom), multi_ss=BlockTreeOrds(blocksize=128)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=nl, timezone=Europe/Istanbul
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40 (32-bit)/cpus=3,threads=1,free=85390960,total=347078656
[junit4] 2> NOTE: All tests run in this JVM: [TestAtomicUpdateErrorCases, SolrInfoMBeanTest, TestSchemaVersionResource, TestExactStatsCache, TestNRTOpen, DateRangeFieldTest, RecoveryZkTest, TestAnalyzedSuggestions, TestFieldCollectionResource, UnloadDistributedZkTest, TestReplicaProperties, TestPerFieldSimilarity, SliceStateTest, SolrCoreCheckLockOnStartupTest, TestRandomDVFaceting, UniqFieldsUpdateProcessorFactoryTest, TestStandardQParsers, TestCryptoKeys, LeaderElectionTest, DistributedFacetPivotWhiteBoxTest, RecoveryAfterSoftCommitTest, HdfsBasicDistributedZkTest, DistanceUnitsTest, DirectUpdateHandlerTest, TestManagedSchemaDynamicFieldResource, CoreAdminRequestStatusTest, TestArbitraryIndexDir, SuggesterWFSTTest, SystemInfoHandlerTest, TestPivotHelperCode, TestRecoveryHdfs, TestCustomSort, TestLRUCache, TestSolrXml, TestTolerantSearch, BadCopyFieldTest, TestLeaderElectionZkExpiry, LukeRequestHandlerTest, CollectionsAPIAsyncDistributedZkTest, TestHighFrequencyDictionaryFactory, TestDynamicFieldResource, HdfsCollectionsAPIDistributedZkTest, TestLazyCores, TestWordDelimiterFilterFactory, SimpleFacetsTest, OutputWriterTest, TestFaceting, DistributedExpandComponentTest, CacheHeaderTest, TestHighlightDedupGrouping, AtomicUpdatesTest, TestCloudSchemaless, TestPartialUpdateDeduplication, TestBM25SimilarityFactory, TestCollationField, DistributedQueryElevationComponentTest, TestQueryUtils, TestExtendedDismaxParser, TestBinaryField, TestShortCircuitedRequests, RequestLoggingTest, ReplicationFactorTest, PreAnalyzedFieldTest, ChangedSchemaMergeTest, TestReplicationHandler, TimeZoneUtilsTest, TestCharFilters, TestConfigSets, DirectSolrConnectionTest, TestJmxMonitoredMap, AssignTest, TestClusterStateMutator, DocumentAnalysisRequestHandlerTest, DefaultValueUpdateProcessorTest, ChaosMonkeyNothingIsSafeTest, TestClassNameShortening, FieldAnalysisRequestHandlerTest, QueryElevationComponentTest, RequiredFieldsTest, SoftAutoCommitTest, DocValuesMultiTest, TestManagedSchemaFieldTypeResource, TestCSVLoader, TestStressLucene, OverriddenZkACLAndCredentialsProvidersTest, TestNoOpRegenerator, DistributedFacetPivotSmallAdvancedTest, TestManagedSchemaFieldResource, AliasIntegrationTest, DistributedSuggestComponentTest, DocValuesTest, MigrateRouteKeyTest, TestSort, PrimUtilsTest, TestSweetSpotSimilarityFactory, VMParamsZkACLAndCredentialsProvidersTest, TestOrdValues, SolrPluginUtilsTest, ZkStateWriterTest, DOMUtilTest, ScriptEngineTest, TestCloudInspectUtil, TestPhraseSuggestions, AnalysisAfterCoreReloadTest, TestTrackingShardHandlerFactory, AddSchemaFieldsUpdateProcessorFactoryTest, BJQParserTest, TestCSVResponseWriter, TestLFUCache, HardAutoCommitTest, IndexSchemaRuntimeFieldTest, HdfsChaosMonkeySafeLeaderTest, FacetPivotSmallTest, MergeStrategyTest, ShardRoutingTest, CollectionsAPIDistributedZkTest, OverseerTest, ClusterStateUpdateTest, TestRandomFaceting, ZkSolrClientTest, TestDistributedGrouping, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestJoin, TestStressVersions, TestRangeQuery, SolrCoreTest, SpellCheckComponentTest, SolrCmdDistributorTest, ConvertedLegacyTest, TestFunctionQuery, BasicFunctionalityTest, ShowFileRequestHandlerTest, CurrencyFieldXmlFileTest, SpellCheckCollatorTest, SuggesterFSTTest, SuggesterTSTTest, TestTrie, SpatialFilterTest, WordBreakSolrSpellCheckerTest, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, DirectSolrSpellCheckerTest, PrimitiveFieldTypeTest, XmlUpdateRequestHandlerTest, PathHierarchyTokenizerFactoryTest, LoggingHandlerTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, QueryParsingTest, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, TestComponentsName, HighlighterConfigTest, TestQuerySenderListener, AlternateDirectoryTest, CopyFieldTest, BadComponentTest, TestSolrDeletionPolicy2, SampleTest, NumericFieldsTest, TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, TestXIncludeConfig, TestIBSimilarityFactory, TestFastOutputStream, URLClassifyProcessorTest, DistributedIntervalFacetingTest, ConnectionManagerTest, DeleteReplicaTest, DistribCursorPagingTest, ExternalCollectionsTest, HttpPartitionTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, SharedFSAutoReplicaFailoverUtilsTest, SolrXmlInZkTest, TestCloudPivotFacet, TestCollectionAPI, TestDistribDocBasedVersion, TestRequestStatusCollectionAPI, TriLevelCompositeIdRoutingTest, TestImplicitCoreProperties, TestInfoStreamLogging, TestInitParams, TestReloadAndDeleteDocs, TestReplicationHandlerBackup, TestSolrConfigHandlerConcurrent, DistributedFacetPivotLargeTest, TestExpandComponent, HighlighterMaxOffsetTest, TestChildDocTransformer, TestRawResponseWriter, TestSortingResponseWriter, TestManagedResource, TestManagedResourceStorage, TestRestManager, TestBulkSchemaAPI, TestCopyFieldCollectionResource, TestDefaultSearchFieldResource, TestDynamicFieldCollectionResource, TestFieldResource, TestFieldTypeCollectionResource, TestFieldTypeResource, TestSchemaResource, TestSchemaSimilarityResource, TestSerializedLuceneMatchVersion, TestSolrQueryParserDefaultOperatorResource, TestSolrQueryParserResource, TestUniqueKeyFieldResource, TestManagedStopFilterFactory, TestManagedSynonymFilterFactory, EnumFieldTest, SpatialRPTFieldTypeTest, TestCloudManagedSchema, TestCollationFieldDocValues, TestManagedSchema, TestSchemaManager, AnalyticsMergeStrategyTest, AnalyticsQueryTest, TestAddFieldRealTimeGet, TestComplexPhraseQParserPlugin, TestFieldSortValues, TestHashQParserPlugin, TestMaxScoreQueryParser, TestMissingGroups, TestReRankQParserPlugin, TestSearcherReuse, TestSolr4Spatial2, TestJsonFacets]
[junit4] Completed on J0 in 6.68s, 4 tests, 1 error <<< FAILURES!
[...truncated 78 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958: There were test failures: 477 suites, 1895 tests, 1 error, 58 ignored (30 assumptions)
Total time: 55 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_40 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any