Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2150/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.TestHighlightDedupGrouping.test Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:60272/yp/c/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:60272/yp/c/collection1 at __randomizedtesting.SeedInfo.seed([6BCEC8AD74D6A23D:E39AF777DA2ACFC5]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:572) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:174) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:139) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:153) at org.apache.solr.TestHighlightDedupGrouping.addDoc(TestHighlightDedupGrouping.java:122) at org.apache.solr.TestHighlightDedupGrouping.randomizedTest(TestHighlightDedupGrouping.java:96) at org.apache.solr.TestHighlightDedupGrouping.test(TestHighlightDedupGrouping.java:42) 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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) at sun.security.ssl.InputRecord.read(InputRecord.java:503) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:961) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:918) at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466) ... 50 more Build Log: [...truncated 10212 lines...] [junit4] Suite: org.apache.solr.TestHighlightDedupGrouping [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/init-core-data-001 [junit4] 2> 1124590 T6495 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 1124591 T6495 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /yp/c [junit4] 2> 1125075 T6495 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1 [junit4] 2> 1125078 T6495 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1125099 T6495 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1125106 T6495 oejs.AbstractConnector.doStart Started [email protected]:60271 [junit4] 2> 1125107 T6495 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/yp/c, hostPort=60271, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores} [junit4] 2> 1125109 T6495 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482 [junit4] 2> 1125110 T6495 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/' [junit4] 2> 1125155 T6495 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/solr.xml [junit4] 2> 1125167 T6495 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores [junit4] 2> 1125167 T6495 oasc.CoreContainer.<init> New CoreContainer 430432933 [junit4] 2> 1125168 T6495 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/] [junit4] 2> 1125168 T6495 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/lib [junit4] 2> 1125169 T6495 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/lib). [junit4] 2> 1125180 T6495 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1125202 T6495 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1125205 T6495 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1125205 T6495 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1125205 T6495 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1125219 T6495 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores [junit4] 2> 1125221 T6495 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 1125221 T6495 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1/ [junit4] 2> 1125221 T6495 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1125222 T6505 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1125223 T6505 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1125223 T6505 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1125256 T6505 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1125264 T6505 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1125282 T6505 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1125283 T6505 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1125288 T6505 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1125381 T6505 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1125390 T6505 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1125390 T6505 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1125396 T6505 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1125398 T6505 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1125400 T6505 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1125401 T6505 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1125401 T6505 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1125401 T6505 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1125402 T6505 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1125402 T6505 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1125402 T6505 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1 [junit4] 2> 1125402 T6505 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1125403 T6505 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1/data/ [junit4] 2> 1125403 T6505 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dd9bb7f [junit4] 2> 1125403 T6505 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1/data [junit4] 2> 1125404 T6505 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 1125404 T6505 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1125404 T6505 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 1125405 T6505 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=14.2119140625, floorSegmentMB=0.2783203125, forceMergeDeletesPctAllowed=15.314590337441796, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8733365889503556 [junit4] 2> 1125405 T6505 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7d5e6346 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef6859),segFN=segments_1,generation=1} [junit4] 2> 1125405 T6505 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1125409 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1125409 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1125409 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1125409 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1125409 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1125410 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1125410 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1125410 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1125410 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1125410 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1125411 T6505 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1125411 T6505 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1125418 T6505 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1125419 T6505 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1125421 T6505 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1125422 T6505 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1125429 T6505 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1125431 T6505 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 1125432 T6505 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1125433 T6505 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1125435 T6505 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1125435 T6505 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1125437 T6505 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1125438 T6505 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1125438 T6505 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=56.2275390625, floorSegmentMB=1.6591796875, forceMergeDeletesPctAllowed=29.16266052662138, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39718801615887533 [junit4] 2> 1125438 T6505 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7d5e6346 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef6859),segFN=segments_1,generation=1} [junit4] 2> 1125438 T6505 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1125439 T6505 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@746b2c7[collection1] main [junit4] 2> 1125439 T6505 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1125440 T6505 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1125440 T6505 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1125440 T6505 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1125448 T6505 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1125499 T6505 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1125499 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1125500 T6505 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1125500 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1125500 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1125501 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1125501 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1125502 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1125503 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1125504 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1125505 T6506 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1125505 T6506 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@746b2c7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1125513 T6495 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1 [junit4] 2> 1125513 T6495 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1126052 T6495 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 1126054 T6495 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1126078 T6495 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1126080 T6495 oejs.AbstractConnector.doStart Started [email protected]:60272 [junit4] 2> 1126080 T6495 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/yp/c, hostPort=60272, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores} [junit4] 2> 1126082 T6495 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482 [junit4] 2> 1126082 T6495 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/' [junit4] 2> 1126130 T6495 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/solr.xml [junit4] 2> 1126140 T6495 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores [junit4] 2> 1126140 T6495 oasc.CoreContainer.<init> New CoreContainer 440794468 [junit4] 2> 1126141 T6495 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/] [junit4] 2> 1126141 T6495 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/lib [junit4] 2> 1126141 T6495 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/lib). [junit4] 2> 1126161 T6495 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1126167 T6495 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1126169 T6495 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1126169 T6495 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1126170 T6495 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1126172 T6495 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores [junit4] 2> 1126174 T6495 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 1126174 T6495 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 1126174 T6495 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1126175 T6517 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1126176 T6517 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1126176 T6517 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1126206 T6517 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1126213 T6517 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1126230 T6517 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1126230 T6517 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1126235 T6517 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1126325 T6517 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1126332 T6517 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1126333 T6517 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1126339 T6517 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1126341 T6517 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1126342 T6517 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1126342 T6517 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1126343 T6517 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1126343 T6517 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1126343 T6517 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1126343 T6517 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1126343 T6517 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1 [junit4] 2> 1126344 T6517 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1126344 T6517 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1/data/ [junit4] 2> 1126344 T6517 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dd9bb7f [junit4] 2> 1126345 T6517 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 1126345 T6517 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 1126345 T6517 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1126345 T6517 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 1126346 T6517 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=14.2119140625, floorSegmentMB=0.2783203125, forceMergeDeletesPctAllowed=15.314590337441796, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8733365889503556 [junit4] 2> 1126346 T6517 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7583d86c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f87dbca),segFN=segments_1,generation=1} [junit4] 2> 1126346 T6517 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1126351 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1126352 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1126352 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1126352 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1126352 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1126352 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1126352 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1126352 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1126353 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1126353 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1126353 T6517 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1126353 T6517 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1126361 T6517 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1126363 T6517 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1126364 T6517 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1126365 T6517 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1126373 T6517 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1126376 T6517 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 1126377 T6517 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1126377 T6517 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1126379 T6517 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1126379 T6517 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1126381 T6517 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1126381 T6517 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1126382 T6517 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=56.2275390625, floorSegmentMB=1.6591796875, forceMergeDeletesPctAllowed=29.16266052662138, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39718801615887533 [junit4] 2> 1126382 T6517 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7583d86c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f87dbca),segFN=segments_1,generation=1} [junit4] 2> 1126383 T6517 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1126383 T6517 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@2295f907[collection1] main [junit4] 2> 1126383 T6517 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1126383 T6517 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1126383 T6517 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1126384 T6517 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1126390 T6517 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1126418 T6517 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1126419 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1126419 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1126420 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1126420 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1126420 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1126421 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1126422 T6517 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1126424 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1126424 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1126425 T6495 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1 [junit4] 2> 1126425 T6495 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1126427 T6518 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1126427 T6518 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2295f907[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1126925 T6495 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 1126936 T6495 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1126942 T6495 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1126943 T6495 oejs.AbstractConnector.doStart Started [email protected]:60273 [junit4] 2> 1126944 T6495 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/yp/c, hostPort=60273, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores} [junit4] 2> 1126946 T6495 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482 [junit4] 2> 1126947 T6495 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/' [junit4] 2> 1126986 T6495 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/solr.xml [junit4] 2> 1126996 T6495 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores [junit4] 2> 1126996 T6495 oasc.CoreContainer.<init> New CoreContainer 1100700403 [junit4] 2> 1126996 T6495 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/] [junit4] 2> 1126996 T6495 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/lib [junit4] 2> 1126997 T6495 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/lib). [junit4] 2> 1127007 T6495 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1127014 T6495 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1127030 T6495 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1127031 T6495 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1127031 T6495 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1127033 T6495 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores [junit4] 2> 1127034 T6495 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 1127034 T6495 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/ [junit4] 2> 1127035 T6495 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1127036 T6529 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1127037 T6529 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1127037 T6529 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1127068 T6529 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1127076 T6529 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1127100 T6529 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1127101 T6529 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1127105 T6529 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1127223 T6529 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1127231 T6529 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1127232 T6529 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1127243 T6529 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1127245 T6529 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1127247 T6529 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1127248 T6529 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1127248 T6529 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1127248 T6529 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1127248 T6529 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1127248 T6529 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1127249 T6529 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1 [junit4] 2> 1127249 T6529 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1127249 T6529 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/data/ [junit4] 2> 1127250 T6529 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dd9bb7f [junit4] 2> 1127255 T6529 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/data [junit4] 2> 1127256 T6529 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/data/index/ [junit4] 2> 1127256 T6529 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1127256 T6529 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/data/index [junit4] 2> 1127257 T6529 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=14.2119140625, floorSegmentMB=0.2783203125, forceMergeDeletesPctAllowed=15.314590337441796, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8733365889503556 [junit4] 2> 1127257 T6529 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@20724ebf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3bfce4),segFN=segments_1,generation=1} [junit4] 2> 1127257 T6529 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1127262 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1127262 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1127263 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1127263 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1127263 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1127263 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1127263 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1127264 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1127264 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1127264 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1127264 T6529 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1127265 T6529 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1127271 T6529 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1127273 T6529 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1127274 T6529 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1127286 T6529 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1127293 T6529 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1127296 T6529 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 1127296 T6529 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1127298 T6529 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1127300 T6529 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1127300 T6529 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1127302 T6529 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1127302 T6529 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1127302 T6529 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=56.2275390625, floorSegmentMB=1.6591796875, forceMergeDeletesPctAllowed=29.16266052662138, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39718801615887533 [junit4] 2> 1127303 T6529 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@20724ebf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3bfce4),segFN=segments_1,generation=1} [junit4] 2> 1127303 T6529 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1127304 T6529 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@20fd6424[collection1] main [junit4] 2> 1127304 T6529 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1127304 T6529 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1127304 T6529 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1127305 T6529 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1127315 T6529 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1127363 T6529 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1127363 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1127364 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1127364 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1127364 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1127365 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1127366 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1127366 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1127368 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1127368 T6530 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1127369 T6530 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@20fd6424[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1127370 T6529 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1127371 T6495 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1 [junit4] 2> 1127371 T6495 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1127374 T6495 oas.SolrTestCaseJ4.setUp ###Starting test [junit4] 2> ASYNC NEW_CORE C2814 name=collection1 org.apache.solr.core.SolrCore@6c1043b8 [junit4] 2> 1127447 T6504 c:collection1 C2814 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498869033175351296)} 0 2 [junit4] 2> ASYNC NEW_CORE C2815 name=collection1 org.apache.solr.core.SolrCore@60ea545 [junit4] 2> 1127550 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498869033259237376)} 0 25 [junit4] 2> ASYNC NEW_CORE C2816 name=collection1 org.apache.solr.core.SolrCore@7cdc67e [junit4] 2> 1127665 T6526 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498869033395552256)} 0 11 [junit4] 2> 1127673 T6503 c:collection1 C2814 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1127673 T6503 c:collection1 C2814 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 1127675 T6503 c:collection1 C2814 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 1127676 T6503 c:collection1 C2814 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1127676 T6503 c:collection1 C2814 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 3 [junit4] 2> 1127692 T6515 c:collection1 C2815 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1127693 T6515 c:collection1 C2815 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 1127694 T6515 c:collection1 C2815 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 1127694 T6515 c:collection1 C2815 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1127694 T6515 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 2 [junit4] 2> 1127710 T6528 c:collection1 C2816 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1127710 T6528 c:collection1 C2816 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 1127711 T6528 c:collection1 C2816 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 1127712 T6528 c:collection1 C2816 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1127713 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 3 [junit4] 2> 1127721 T6512 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[1 (1498869033461612544)]} 0 3 [junit4] 2> 1127730 T6514 c:collection1 C2815 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1127754 T6514 c:collection1 C2815 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7583d86c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f87dbca),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7583d86c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f87dbca),segFN=segments_2,generation=2} [junit4] 2> 1127757 T6514 c:collection1 C2815 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1127758 T6514 c:collection1 C2815 oass.SolrIndexSearcher.<init> Opening Searcher@790cbaca[collection1] main [junit4] 2> 1127758 T6514 c:collection1 C2815 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1127759 T6518 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@790cbaca[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C1)))} [junit4] 2> 1127760 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 30 [junit4] 2> 1127778 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[1 (1498869033522429952)]} 0 2 [junit4] 2> 1127785 T6527 c:collection1 C2816 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1127793 T6527 c:collection1 C2816 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@20724ebf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3bfce4),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@20724ebf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3bfce4),segFN=segments_2,generation=2} [junit4] 2> 1127794 T6527 c:collection1 C2816 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1127806 T6527 c:collection1 C2816 oass.SolrIndexSearcher.<init> Opening Searcher@14d43f44[collection1] main [junit4] 2> 1127807 T6527 c:collection1 C2816 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1127814 T6530 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14d43f44[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C1)))} [junit4] 2> 1127814 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 29 [junit4] 2> 1128073 T6523 c:collection1 C2816 oasc.SolrCore.execute [collection1] webapp=/yp/c path=/select params={group.distributed.first=true&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=2048&start=0&shard.url=https://127.0.0.1:60273/yp/c/collection1&rows=10&version=2&q=id_s1:1&NOW=1429432900976&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=103 [junit4] 2> 1128075 T6515 c:collection1 C2815 oasc.SolrCore.execute [collection1] webapp=/yp/c path=/select params={group.distributed.first=true&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=2048&start=0&shard.url=https://127.0.0.1:60272/yp/c/collection1&rows=10&version=2&q=id_s1:1&NOW=1429432900976&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=104 [junit4] 2> 1128220 T6511 c:collection1 C2815 oasc.SolrCore.execute [collection1] webapp=/yp/c path=/select params={group.topgroups.id_s1=1&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=4&start=0&shard.url=https://127.0.0.1:60272/yp/c/collection1&rows=10&version=2&group.distributed.second=true&q=id_s1:1&NOW=1429432900976&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=61 [junit4] 2> 1128222 T6527 c:collection1 C2816 oasc.SolrCore.execute [collection1] webapp=/yp/c path=/select params={group.topgroups.id_s1=1&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=4&start=0&shard.url=https://127.0.0.1:60273/yp/c/collection1&rows=10&version=2&group.distributed.second=true&q=id_s1:1&NOW=1429432900976&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=46 [junit4] 2> 1128290 T6528 c:collection1 C2816 oasc.SolrCore.execute [collection1] webapp=/yp/c path=/select params={distrib=false&hl=true&group.limit=2&shards.purpose=192&shard.url=https://127.0.0.1:60273/yp/c/collection1&version=2&q=id_s1:1&NOW=1429432900976&ids=1&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1} status=0 QTime=2 [junit4] 2> 1128298 T6516 c:collection1 C2815 oasc.SolrCore.execute [collection1] webapp=/yp/c path=/select params={distrib=false&hl=true&group.limit=2&shards.purpose=192&shard.url=https://127.0.0.1:60272/yp/c/collection1&version=2&q=id_s1:1&NOW=1429432900976&ids=1&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1} status=0 QTime=1 [junit4] 2> 1128333 T6513 c:collection1 C2815 oasc.SolrCore.execute [collection1] webapp=/yp/c path=/select params={q=id_s1:1&shards=https://127.0.0.1:60272/yp/c/collection1,https://127.0.0.1:60273/yp/c/collection1&hl=true&group.limit=2&hl.fl=id_s1&wt=javabin&version=2&group.field=id_s1&group=true} status=0 QTime=508 [junit4] 2> 1128383 T6500 c:collection1 C2814 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498869034156818432)} 0 2 [junit4] 2> 1128389 T6511 c:collection1 C2815 oass.SolrIndexSearcher.<init> Opening Searcher@2333824c[collection1] realtime [junit4] 2> 1128389 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498869034162061312)} 0 3 [junit4] 2> 1128408 T6527 c:collection1 C2816 oass.SolrIndexSearcher.<init> Opening Searcher@7d653bd1[collection1] realtime [junit4] 2> 1128408 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498869034169401344)} 0 15 [junit4] 2> 1128412 T6502 c:collection1 C2814 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1128412 T6502 c:collection1 C2814 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 1128413 T6502 c:collection1 C2814 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 1128413 T6502 c:collection1 C2814 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1128414 T6502 c:collection1 C2814 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 1 [junit4] 2> 1128425 T6515 c:collection1 C2815 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1128435 T6515 c:collection1 C2815 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7583d86c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f87dbca),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7583d86c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f87dbca),segFN=segments_3,generation=3} [junit4] 2> 1128436 T6515 c:collection1 C2815 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1128436 T6515 c:collection1 C2815 oass.SolrIndexSearcher.<init> Opening Searcher@2736c990[collection1] main [junit4] 2> 1128436 T6515 c:collection1 C2815 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1128437 T6518 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2736c990[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1128438 T6515 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 13 [junit4] 2> 1128449 T6523 c:collection1 C2816 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1128450 T6523 c:collection1 C2816 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@20724ebf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3bfce4),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@20724ebf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d3bfce4),segFN=segments_3,generation=3} [junit4] 2> 1128450 T6523 c:collection1 C2816 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1128451 T6523 c:collection1 C2816 oass.SolrIndexSearcher.<init> Opening Searcher@244ef56d[collection1] main [junit4] 2> 1128453 T6530 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@244ef56d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1128453 T6523 c:collection1 C2816 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1128453 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 4 [junit4] 2> 1128493 T6516 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[0 (1498869034235461632)]} 0 37 [junit4] 2> 1128508 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[1 (1498869034289987584)]} 0 0 [junit4] 2> 1128513 T6513 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[2 (1498869034295230464)]} 0 0 [junit4] 2> 1128536 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[3 (1498869034300473344)]} 0 18 [junit4] 2> 1128541 T6524 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[4 (1498869034324590592)]} 0 1 [junit4] 2> 1128551 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[5 (1498869034334027776)]} 0 1 [junit4] 2> 1128565 T6526 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[6 (1498869034349756416)]} 0 0 [junit4] 2> 1128584 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[7 (1498869034368630784)]} 0 1 [junit4] 2> 1128599 T6516 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[8 (1498869034385408000)]} 0 0 [junit4] 2> 1128603 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[9 (1498869034389602304)]} 0 0 [junit4] 2> 1128610 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[10 (1498869034396942336)]} 0 0 [junit4] 2> 1128618 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[11 (1498869034404282368)]} 0 1 [junit4] 2> 1128635 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[12 (1498869034423156736)]} 0 0 [junit4] 2> 1128651 T6526 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[13 (1498869034438885376)]} 0 1 [junit4] 2> 1128654 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[14 (1498869034443079680)]} 0 0 [junit4] 2> 1128657 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[15 (1498869034446225408)]} 0 0 [junit4] 2> 1128662 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[16 (1498869034451468288)]} 0 0 [junit4] 2> 1128669 T6513 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[17 (1498869034458808320)]} 0 0 [junit4] 2> 1128687 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[18 (1498869034477682688)]} 0 1 [junit4] 2> 1128706 T6512 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[19 (1498869034497605632)]} 0 0 [junit4] 2> 1128711 T6515 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[20 (1498869034502848512)]} 0 0 [junit4] 2> 1128729 T6524 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[21 (1498869034520674304)]} 0 1 [junit4] 2> 1128745 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[22 (1498869034538500096)]} 0 0 [junit4] 2> 1128764 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[23 (1498869034558423040)]} 0 0 [junit4] 2> 1128772 T6516 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[24 (1498869034566811648)]} 0 0 [junit4] 2> 1128777 T6526 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[25 (1498869034572054528)]} 0 1 [junit4] 2> 1128781 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[26 (1498869034576248832)]} 0 1 [junit4] 2> 1128787 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[27 (1498869034582540288)]} 0 0 [junit4] 2> 1128791 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[28 (1498869034586734592)]} 0 0 [junit4] 2> 1128798 T6513 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[29 (1498869034594074624)]} 0 0 [junit4] 2> 1128833 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[30 (1498869034630774784)]} 0 0 [junit4] 2> 1128847 T6524 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[31 (1498869034644406272)]} 0 1 [junit4] 2> 1128855 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[32 (1498869034653843456)]} 0 0 [junit4] 2> 1128896 T6512 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[33 (1498869034695786496)]} 0 1 [junit4] 2> 1128909 T6515 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[34 (1498869034709417984)]} 0 1 [junit4] 2> 1128918 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[35 (1498869034719903744)]} 0 0 [junit4] 2> 1128928 T6516 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[36 (1498869034730389504)]} 0 0 [junit4] 2> 1128935 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[37 (1498869034737729536)]} 0 0 [junit4] 2> 1128939 T6526 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[38 (1498869034741923840)]} 0 0 [junit4] 2> 1128958 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[39 (1498869034761846784)]} 0 0 [junit4] 2> 1128981 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[40 (1498869034785964032)]} 0 0 [junit4] 2> 1128990 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[41 (1498869034795401216)]} 0 0 [junit4] 2> 1129018 T6512 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[42 (1498869034824761344)]} 0 0 [junit4] 2> 1129042 T6524 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[43 (1498869034849927168)]} 0 0 [junit4] 2> 1129054 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[44 (1498869034862510080)]} 0 1 [junit4] 2> 1129070 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[45 (1498869034879287296)]} 0 0 [junit4] 2> 1129085 T6516 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[46 (1498869034895015936)]} 0 0 [junit4] 2> 1129090 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[47 (1498869034900258816)]} 0 0 [junit4] 2> 1129098 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[48 (1498869034908647424)]} 0 0 [junit4] 2> 1129108 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[49 (1498869034918084608)]} 0 1 [junit4] 2> 1129121 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[50 (1498869034932764672)]} 0 0 [junit4] 2> 1129124 T6524 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[51 (1498869034935910400)]} 0 0 [junit4] 2> 1129145 T6513 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[52 (1498869034957930496)]} 0 1 [junit4] 2> 1129150 T6512 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[53 (1498869034963173376)]} 0 0 [junit4] 2> 1129154 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[54 (1498869034967367680)]} 0 0 [junit4] 2> 1129171 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[55 (1498869034985193472)]} 0 0 [junit4] 2> 1129201 T6516 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[56 (1498869035015602176)]} 0 1 [junit4] 2> 1129206 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[57 (1498869035021893632)]} 0 0 [junit4] 2> 1129219 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[58 (1498869035035525120)]} 0 0 [junit4] 2> 1129231 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[59 (1498869035048108032)]} 0 0 [junit4] 2> 1129239 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[60 (1498869035056496640)]} 0 1 [junit4] 2> 1129243 T6515 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[61 (1498869035060690944)]} 0 0 [junit4] 2> 1129264 T6513 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[62 (1498869035082711040)]} 0 1 [junit4] 2> 1129305 T6524 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[63 (1498869035125702656)]} 0 0 [junit4] 2> 1129316 T6526 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[64 (1498869035137236992)]} 0 0 [junit4] 2> 1129337 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[65 (1498869035158208512)]} 0 5 [junit4] 2> 1129347 T6512 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[66 (1498869035169742848)]} 0 0 [junit4] 2> 1129361 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[67 (1498869035184422912)]} 0 0 [junit4] 2> 1129379 T6511 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[68 (1498869035203297280)]} 0 0 [junit4] 2> 1129403 T6516 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[69 (1498869035228463104)]} 0 0 [junit4] 2> 1129411 T6514 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[70 (1498869035236851712)]} 0 0 [junit4] 2> 1129425 T6525 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[71 (1498869035251531776)]} 0 1 [junit4] 2> 1129433 T6515 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[72 (1498869035259920384)]} 0 0 [junit4] 2> 1129451 T6512 c:collection1 C2815 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[73 (1498869035278794752)]} 0 0 [junit4] 2> 1129455 T6524 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[73 (1498869035282989056)]} 0 0 [junit4] 2> 1129480 T6527 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[74 (1498869035309203456)]} 0 1 [junit4] 2> 1129484 T6523 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[75 (1498869035313397760)]} 0 0 [junit4] 2> 1129494 T6528 c:collection1 C2816 oasup.LogUpdateProcessor.finish [collection1] webapp=/yp/c path=/update params={wt=javabin&version=2} {add=[76 (149886 [...truncated too long message...] ng directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001/tempDir-001/shard1/cores/collection1/data [junit4] 2> 1224067 T6495 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/yp/c,null} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestHighlightDedupGrouping -Dtests.method=test -Dtests.seed=6BCEC8AD74D6A23D -Dtests.slow=true -Dtests.locale=de_AT -Dtests.timezone=America/Ensenada -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 99.7s J1 | TestHighlightDedupGrouping.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:60272/yp/c/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([6BCEC8AD74D6A23D:E39AF777DA2ACFC5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:572) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:174) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:139) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:153) [junit4] > at org.apache.solr.TestHighlightDedupGrouping.addDoc(TestHighlightDedupGrouping.java:122) [junit4] > at org.apache.solr.TestHighlightDedupGrouping.randomizedTest(TestHighlightDedupGrouping.java:96) [junit4] > at org.apache.solr.TestHighlightDedupGrouping.test(TestHighlightDedupGrouping.java:42) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:170) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:141) [junit4] > at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) [junit4] > at sun.security.ssl.InputRecord.read(InputRecord.java:503) [junit4] > at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:961) [junit4] > at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:918) [junit4] > at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) [junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) [junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:466) [junit4] > ... 50 more [junit4] 2> 1224292 T6495 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 6BCEC8AD74D6A23D-001 [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=de_AT, timezone=America/Ensenada [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=131477568,total=356515840 [junit4] 2> NOTE: All tests run in this JVM: [UpdateRequestProcessorFactoryTest, TestHashQParserPlugin, TestQuerySenderNoQuery, TestSolrDeletionPolicy2, TestChildDocTransformer, DocumentBuilderTest, TestRealTimeGet, DistributedFacetPivotLongTailTest, HdfsThreadLeakTest, DistributedFacetPivotLargeTest, TestDefaultStatsCache, HdfsBasicDistributedZk2Test, IndexSchemaTest, TestOverriddenPrefixQueryForCustomFieldType, TestFoldingMultitermQuery, TestReload, InfoHandlerTest, TestDocSet, TestSchemaResource, SimpleMLTQParserTest, IndexBasedSpellCheckerTest, SchemaVersionSpecificBehaviorTest, MBeansHandlerTest, ShardRoutingCustomTest, TestRestManager, TestQuerySenderListener, RAMDirectoryFactoryTest, TestFieldTypeResource, DistributedQueryComponentCustomSortTest, ZkStateWriterTest, TestMergePolicyConfig, TestMacros, TestSolr4Spatial, TestSystemIdResolver, TestSimpleTrackingShardHandler, FacetPivotSmallTest, TestRandomMergePolicy, FieldMutatingUpdateProcessorTest, DistribJoinFromCollectionTest, TestBlobHandler, TestStandardQParsers, QueryResultKeyTest, TestRangeQuery, CollectionReloadTest, TestReversedWildcardFilterFactory, CollectionsAPIAsyncDistributedZkTest, HttpPartitionTest, TriLevelCompositeIdRoutingTest, StandardRequestHandlerTest, TestSerializedLuceneMatchVersion, FastVectorHighlighterTest, TestCloudSchemaless, TestBulkSchemaConcurrent, SSLMigrationTest, TestDefaultSimilarityFactory, SpellingQueryConverterTest, TestSortingResponseWriter, SimplePostToolTest, TestSolrDynamicMBean, ChaosMonkeySafeLeaderTest, TestDynamicLoading, CopyFieldTest, LeaderInitiatedRecoveryOnCommitTest, TestConfigReload, ExitableDirectoryReaderTest, AliasIntegrationTest, TestBadConfig, BadIndexSchemaTest, TestSolrConfigHandlerCloud, ShardSplitTest, TestLMJelinekMercerSimilarityFactory, RegexBoostProcessorTest, SolrIndexConfigTest, ChangedSchemaMergeTest, TermVectorComponentTest, TestInitParams, TermsComponentTest, OutOfBoxZkACLAndCredentialsProvidersTest, SignatureUpdateProcessorFactoryTest, QueryEqualityTest, WordBreakSolrSpellCheckerTest, TestSchemaVersionResource, PrimitiveFieldTypeTest, TestPseudoReturnFields, TestLRUStatsCache, HdfsSyncSliceTest, TestTolerantSearch, TestFuzzyAnalyzedSuggestions, SpatialFilterTest, SolrTestCaseJ4Test, TestFieldTypeCollectionResource, SolrCmdDistributorTest, TestPHPSerializedResponseWriter, BinaryUpdateRequestHandlerTest, SpellCheckCollatorTest, TestFastOutputStream, TestPivotHelperCode, BasicFunctionalityTest, PluginInfoTest, TestCopyFieldCollectionResource, DateMathParserTest, TestFiltering, VMParamsZkACLAndCredentialsProvidersTest, ZkSolrClientTest, HdfsWriteToMultipleCollectionsTest, HdfsBasicDistributedZkTest, TestImplicitCoreProperties, ReplicationFactorTest, NotRequiredUniqueKeyTest, DebugComponentTest, DistributedTermsComponentTest, CursorPagingTest, TestSolrDeletionPolicy1, OverseerCollectionProcessorTest, TestHighlightDedupGrouping] [junit4] Completed [233/483] on J1 in 99.76s, 1 test, 1 error <<< FAILURES! [...truncated 772 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:536: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:484: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:229: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:511: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1434: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:991: There were test failures: 483 suites, 1917 tests, 1 error, 56 ignored (22 assumptions) Total time: 64 minutes 43 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
