Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2125/ Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed. FAILED: org.apache.solr.TestHighlightDedupGrouping.test Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:60145//collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:60145//collection1 at __randomizedtesting.SeedInfo.seed([45E3AC02B9E50801:CDB793D8171965F9]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:567) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) 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:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at 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.read(SocketInputStream.java:152) at java.net.SocketInputStream.read(SocketInputStream.java:122) at sun.security.ssl.InputRecord.readFully(InputRecord.java:442) at sun.security.ssl.InputRecord.read(InputRecord.java:480) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:934) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:891) at sun.security.ssl.AppInputStream.read(AppInputStream.java:102) 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:463) ... 50 more Build Log: [...truncated 10141 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 45E3AC02B9E50801-001/init-core-data-001 [junit4] 2> 1208083 T5916 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 1208083 T5916 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 1208670 T5916 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 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1 [junit4] 2> 1208673 T5916 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1208709 T5916 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1208711 T5916 oejs.AbstractConnector.doStart Started [email protected]:60144 [junit4] 2> 1208712 T5916 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=60144, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores, hostContext=/} [junit4] 2> 1208715 T5916 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@2876b359 [junit4] 2> 1208715 T5916 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/' [junit4] 2> 1208769 T5916 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/solr.xml [junit4] 2> 1208812 T5916 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 45E3AC02B9E50801-001/tempDir-001/control/cores [junit4] 2> 1208812 T5916 oasc.CoreContainer.<init> New CoreContainer 122740122 [junit4] 2> 1208812 T5916 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 45E3AC02B9E50801-001/tempDir-001/control/] [junit4] 2> 1208812 T5916 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/lib [junit4] 2> 1208813 T5916 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 45E3AC02B9E50801-001/tempDir-001/control/lib). [junit4] 2> 1208825 T5916 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1208825 T5916 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https [junit4] 2> 1208825 T5916 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1208825 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1208826 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 1208826 T5916 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1208826 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1208826 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1208826 T5916 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1208826 T5916 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1208827 T5916 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 1208833 T5916 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1208835 T5916 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1208836 T5916 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1208836 T5916 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1208837 T5916 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 45E3AC02B9E50801-001/tempDir-001/control/cores [junit4] 2> 1208838 T5916 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1, config=solrconfig.xml, name=collection1, schema=schema.xml, dataDir=data/, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/, configSet=collection1, transient=false} [junit4] 2> 1208838 T5916 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/ [junit4] 2> 1208839 T5916 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1208840 T5926 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1208841 T5926 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> 1208842 T5926 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> 1208882 T5926 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1208904 T5926 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1208965 T5926 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1208966 T5926 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> 1208973 T5926 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1209306 T5926 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1209318 T5926 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1209321 T5926 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1209328 T5926 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1209331 T5926 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1209333 T5926 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1209334 T5926 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1209334 T5926 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1209334 T5926 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1209335 T5926 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1209335 T5926 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1209335 T5926 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> 1209335 T5926 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1209335 T5926 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 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data/ [junit4] 2> 1209335 T5926 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@868e3cd [junit4] 2> 1209337 T5926 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 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data [junit4] 2> 1209337 T5926 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 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 1209337 T5926 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 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1209337 T5926 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 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 1209337 T5926 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@467bd9a8 [junit4] 2> 1209338 T5926 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d0ec8be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@659d69e1),segFN=segments_1,generation=1} [junit4] 2> 1209338 T5926 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1209344 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1209344 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1209344 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1209344 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1209344 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1209345 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1209345 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1209345 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1209345 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1209346 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1209346 T5926 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1209346 T5926 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1209358 T5926 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1209360 T5926 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1209362 T5926 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1209363 T5926 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1209372 T5926 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1209375 T5926 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /debug/dump,/admin/plugins,mltrh,/replication,defaults,/admin/threads,spellCheckCompRH_Direct,/admin/properties,tvrh,/update/json/docs,spellCheckWithWordbreak_Direct,/get,/admin/ping,spellCheckCompRH,/update/json,dismax,standard,/admin/file,lazy,/search-facet-invariants,/update/csv,/admin/luke,/admin/logging,/mlt,/search-facet-def,/terms,spellCheckWithWordbreak,/update,mock,/admin/system,/config,/schema,spellCheckCompRH1,/admin/segments,/admin/mbeans [junit4] 2> 1209376 T5926 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1209379 T5926 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1209379 T5926 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1209379 T5926 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1209383 T5926 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7854368998779164] [junit4] 2> 1209383 T5926 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d0ec8be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@659d69e1),segFN=segments_1,generation=1} [junit4] 2> 1209384 T5926 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1209384 T5926 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@50c633c4[collection1] main [junit4] 2> 1209384 T5926 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1209384 T5926 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1209384 T5926 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1209384 T5926 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1209392 T5926 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1209421 T5926 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1209421 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1209422 T5926 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1209422 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1209423 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1209423 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1209423 T5916 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1 [junit4] 2> 1209424 T5916 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1209424 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1209425 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1209426 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1209427 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1209428 T5927 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1209429 T5927 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50c633c4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1209938 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 1209940 T5916 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1209946 T5916 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1209948 T5916 oejs.AbstractConnector.doStart Started [email protected]:60145 [junit4] 2> 1209949 T5916 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=60145, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores} [junit4] 2> 1209951 T5916 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@2876b359 [junit4] 2> 1209952 T5916 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/' [junit4] 2> 1209989 T5916 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/solr.xml [junit4] 2> 1210022 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores [junit4] 2> 1210022 T5916 oasc.CoreContainer.<init> New CoreContainer 710512921 [junit4] 2> 1210022 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard0/] [junit4] 2> 1210023 T5916 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/lib [junit4] 2> 1210023 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard0/lib). [junit4] 2> 1210035 T5916 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1210035 T5916 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https [junit4] 2> 1210035 T5916 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1210035 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1210035 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 1210036 T5916 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1210036 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1210036 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1210036 T5916 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1210037 T5916 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1210037 T5916 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 1210041 T5916 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1210045 T5916 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1210045 T5916 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1210045 T5916 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1210047 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores [junit4] 2> 1210048 T5916 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/, loadOnStartup=true, config=solrconfig.xml, transient=false, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1, schema=schema.xml, configSet=collection1, dataDir=data/, name=collection1} [junit4] 2> 1210048 T5916 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 1210048 T5916 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1210050 T5938 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1210051 T5938 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> 1210052 T5938 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> 1210086 T5938 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1210108 T5938 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1210162 T5938 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1210164 T5938 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> 1210170 T5938 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1210519 T5938 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1210529 T5938 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1210531 T5938 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1210538 T5938 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1210542 T5938 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1210545 T5938 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1210547 T5938 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1210547 T5938 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1210547 T5938 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1210547 T5938 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1210548 T5938 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1210548 T5938 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> 1210548 T5938 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1210548 T5938 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data/ [junit4] 2> 1210548 T5938 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@868e3cd [junit4] 2> 1210549 T5938 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 1210549 T5938 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 1210550 T5938 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1210550 T5938 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 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 1210550 T5938 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@244a3ec [junit4] 2> 1210551 T5938 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_1,generation=1} [junit4] 2> 1210551 T5938 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1210559 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1210560 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1210560 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1210560 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1210560 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1210560 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1210560 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1210561 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1210561 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1210562 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1210562 T5938 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1210562 T5938 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1210574 T5938 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1210575 T5938 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1210576 T5938 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1210578 T5938 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1210590 T5938 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1210595 T5938 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /debug/dump,/admin/plugins,mltrh,/replication,defaults,/admin/threads,spellCheckCompRH_Direct,/admin/properties,tvrh,/update/json/docs,spellCheckWithWordbreak_Direct,/get,/admin/ping,spellCheckCompRH,/update/json,dismax,standard,/admin/file,lazy,/search-facet-invariants,/update/csv,/admin/luke,/admin/logging,/mlt,/search-facet-def,/terms,spellCheckWithWordbreak,/update,mock,/admin/system,/config,/schema,spellCheckCompRH1,/admin/segments,/admin/mbeans [junit4] 2> 1210596 T5938 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1210597 T5938 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1210598 T5938 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1210598 T5938 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1210598 T5938 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7854368998779164] [junit4] 2> 1210599 T5938 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_1,generation=1} [junit4] 2> 1210599 T5938 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1210599 T5938 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@5440e914[collection1] main [junit4] 2> 1210600 T5938 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1210600 T5938 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1210600 T5938 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1210600 T5938 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1210608 T5938 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1210639 T5938 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1210639 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1210640 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1210640 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1210640 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1210640 T5938 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1210641 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1210642 T5916 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1 [junit4] 2> 1210642 T5916 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1210642 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1210643 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1210644 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1210645 T5939 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1210646 T5939 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5440e914[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1211188 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 1211191 T5916 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1211212 T5916 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 1211213 T5916 oejs.AbstractConnector.doStart Started [email protected]:60146 [junit4] 2> 1211217 T5916 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/cores, hostContext=/, hostPort=60146} [junit4] 2> 1211220 T5916 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@2876b359 [junit4] 2> 1211220 T5916 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/' [junit4] 2> 1211257 T5916 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/solr.xml [junit4] 2> 1211294 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores [junit4] 2> 1211294 T5916 oasc.CoreContainer.<init> New CoreContainer 727151275 [junit4] 2> 1211295 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard1/] [junit4] 2> 1211295 T5916 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/lib [junit4] 2> 1211295 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard1/lib). [junit4] 2> 1211308 T5916 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1211308 T5916 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https [junit4] 2> 1211309 T5916 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1211309 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1211309 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 1211309 T5916 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1211310 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1211310 T5916 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1211310 T5916 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1211310 T5916 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1211311 T5916 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 1211318 T5916 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1211320 T5916 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1211320 T5916 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1211320 T5916 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1211322 T5916 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores [junit4] 2> 1211323 T5916 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {schema=schema.xml, transient=false, name=collection1, configSet=collection1, dataDir=data/, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/, config=solrconfig.xml} [junit4] 2> 1211323 T5916 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/ [junit4] 2> 1211323 T5916 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1211325 T5950 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1211326 T5950 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> 1211327 T5950 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> 1211367 T5950 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1211388 T5950 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1211438 T5950 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1211441 T5950 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> 1211448 T5950 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1211772 T5950 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1211785 T5950 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1211786 T5950 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1211794 T5950 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1211797 T5950 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1211801 T5950 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1211803 T5950 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1211803 T5950 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1211803 T5950 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1211804 T5950 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1211804 T5950 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1211804 T5950 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> 1211804 T5950 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1211805 T5950 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data/ [junit4] 2> 1211805 T5950 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@868e3cd [junit4] 2> 1211805 T5950 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data [junit4] 2> 1211806 T5950 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data/index/ [junit4] 2> 1211806 T5950 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1211806 T5950 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 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data/index [junit4] 2> 1211806 T5950 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@7233e11f [junit4] 2> 1211807 T5950 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_1,generation=1} [junit4] 2> 1211807 T5950 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1211819 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1211819 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1211820 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1211820 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1211820 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1211820 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1211820 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1211821 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1211821 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1211821 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1211822 T5950 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1211822 T5950 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1211832 T5950 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1211834 T5950 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1211836 T5950 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1211837 T5950 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1211848 T5950 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1211852 T5950 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /debug/dump,/admin/plugins,mltrh,/replication,defaults,/admin/threads,spellCheckCompRH_Direct,/admin/properties,tvrh,/update/json/docs,spellCheckWithWordbreak_Direct,/get,/admin/ping,spellCheckCompRH,/update/json,dismax,standard,/admin/file,lazy,/search-facet-invariants,/update/csv,/admin/luke,/admin/logging,/mlt,/search-facet-def,/terms,spellCheckWithWordbreak,/update,mock,/admin/system,/config,/schema,spellCheckCompRH1,/admin/segments,/admin/mbeans [junit4] 2> 1211853 T5950 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1211853 T5950 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1211854 T5950 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1211855 T5950 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1211855 T5950 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7854368998779164] [junit4] 2> 1211855 T5950 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_1,generation=1} [junit4] 2> 1211856 T5950 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1211856 T5950 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@62377881[collection1] main [junit4] 2> 1211856 T5950 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1211857 T5950 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1211857 T5950 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1211857 T5950 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1211868 T5950 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1211915 T5950 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1211915 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1211917 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1211917 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1211917 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1211918 T5950 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1211918 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1211919 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1211920 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1211921 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1211922 T5951 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1211923 T5951 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62377881[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1211924 T5916 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1 [junit4] 2> 1211924 T5916 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1211927 T5916 oas.SolrTestCaseJ4.setUp ###Starting test [junit4] 2> ASYNC NEW_CORE C2186 name=collection1 org.apache.solr.core.SolrCore@6a12c317 [junit4] 2> 1211958 T5925 c:collection1 C2186 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 1211960 T5925 c:collection1 C2186 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 2 [junit4] 2> ASYNC NEW_CORE C2187 name=collection1 org.apache.solr.core.SolrCore@316769fb [junit4] 2> 1211993 T5935 c:collection1 C2187 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 1211993 T5935 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1 [junit4] 2> ASYNC NEW_CORE C2188 name=collection1 org.apache.solr.core.SolrCore@5724e67 [junit4] 2> 1212020 T5948 c:collection1 C2188 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 1212021 T5948 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1 [junit4] 2> 1212026 T5922 c:collection1 C2186 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212027 T5922 c:collection1 C2186 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d0ec8be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@659d69e1),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d0ec8be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@659d69e1),segFN=segments_2,generation=2} [junit4] 2> 1212027 T5922 c:collection1 C2186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1212028 T5922 c:collection1 C2186 oass.SolrIndexSearcher.<init> Opening Searcher@4a457e62[collection1] main [junit4] 2> 1212028 T5922 c:collection1 C2186 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212030 T5927 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4a457e62[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1212030 T5922 c:collection1 C2186 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 4 [junit4] 2> 1212034 T5932 c:collection1 C2187 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212035 T5932 c:collection1 C2187 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_2,generation=2} [junit4] 2> 1212036 T5932 c:collection1 C2187 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1212037 T5932 c:collection1 C2187 oass.SolrIndexSearcher.<init> Opening Searcher@4e7a6e46[collection1] main [junit4] 2> 1212037 T5932 c:collection1 C2187 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212039 T5939 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e7a6e46[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1212049 T5932 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 15 [junit4] 2> 1212054 T5947 c:collection1 C2188 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212055 T5947 c:collection1 C2188 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_2,generation=2} [junit4] 2> 1212056 T5947 c:collection1 C2188 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1212056 T5947 c:collection1 C2188 oass.SolrIndexSearcher.<init> Opening Searcher@93e8eb5[collection1] main [junit4] 2> 1212057 T5947 c:collection1 C2188 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212059 T5951 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@93e8eb5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1212059 T5947 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 5 [junit4] 2> 1212065 T5937 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1]} 0 1 [junit4] 2> 1212068 T5933 c:collection1 C2187 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212078 T5933 c:collection1 C2187 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_3,generation=3} [junit4] 2> 1212078 T5933 c:collection1 C2187 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1212080 T5933 c:collection1 C2187 oass.SolrIndexSearcher.<init> Opening Searcher@432a3a81[collection1] main [junit4] 2> 1212080 T5933 c:collection1 C2187 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212082 T5939 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@432a3a81[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C1)))} [junit4] 2> 1212082 T5933 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 14 [junit4] 2> 1212086 T5946 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1]} 0 0 [junit4] 2> 1212090 T5944 c:collection1 C2188 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212093 T5944 c:collection1 C2188 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_3,generation=3} [junit4] 2> 1212093 T5944 c:collection1 C2188 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1212094 T5944 c:collection1 C2188 oass.SolrIndexSearcher.<init> Opening Searcher@2ec279b[collection1] main [junit4] 2> 1212095 T5944 c:collection1 C2188 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212097 T5951 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2ec279b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C1)))} [junit4] 2> 1212097 T5944 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 7 [junit4] 2> 1212152 T5935 c:collection1 C2187 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&isShard=true&NOW=1428922608909&shard.url=https://127.0.0.1:60145//collection1&group=true&group.field=id_s1&version=2&group.limit=2&start=0&shards.purpose=2048&hl.fl=id_s1&rows=10&q=id_s1:1&hl=false&fl=id,score&distrib=false&group.distributed.first=true} status=0 QTime=2 [junit4] 2> 1212157 T5947 c:collection1 C2188 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&isShard=true&NOW=1428922608909&shard.url=https://127.0.0.1:60146//collection1&group=true&group.field=id_s1&version=2&group.limit=2&start=0&shards.purpose=2048&hl.fl=id_s1&rows=10&q=id_s1:1&hl=false&fl=id,score&distrib=false&group.distributed.first=true} status=0 QTime=1 [junit4] 2> 1212167 T5946 c:collection1 C2188 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&isShard=true&NOW=1428922608909&shard.url=https://127.0.0.1:60146//collection1&group=true&group.field=id_s1&version=2&group.distributed.second=true&group.limit=2&start=0&group.topgroups.id_s1=1&shards.purpose=4&hl.fl=id_s1&rows=10&q=id_s1:1&hl=false&fl=id,score&distrib=false} status=0 QTime=2 [junit4] 2> 1212167 T5932 c:collection1 C2187 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&isShard=true&NOW=1428922608909&shard.url=https://127.0.0.1:60145//collection1&group=true&group.field=id_s1&version=2&group.distributed.second=true&group.limit=2&start=0&group.topgroups.id_s1=1&shards.purpose=4&hl.fl=id_s1&rows=10&q=id_s1:1&hl=false&fl=id,score&distrib=false} status=0 QTime=1 [junit4] 2> 1212175 T5944 c:collection1 C2188 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&isShard=true&NOW=1428922608909&shard.url=https://127.0.0.1:60146//collection1&q=id_s1:1&group.field=id_s1&distrib=false&ids=1&hl=true&version=2&group.limit=2&shards.purpose=192&hl.fl=id_s1} status=0 QTime=2 [junit4] 2> 1212182 T5937 c:collection1 C2187 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&isShard=true&NOW=1428922608909&shard.url=https://127.0.0.1:60145//collection1&q=id_s1:1&group.field=id_s1&distrib=false&ids=1&hl=true&version=2&group.limit=2&shards.purpose=192&hl.fl=id_s1} status=0 QTime=0 [junit4] 2> 1212184 T5949 c:collection1 C2188 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&q=id_s1:1&group.field=id_s1&group=true&hl=true&shards=https://127.0.0.1:60145//collection1,https://127.0.0.1:60146//collection1&version=2&group.limit=2&hl.fl=id_s1} status=0 QTime=83 [junit4] 2> 1212189 T5922 c:collection1 C2186 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 1212190 T5922 c:collection1 C2186 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1 [junit4] 2> 1212193 T5933 c:collection1 C2187 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 1212194 T5933 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1 [junit4] 2> 1212198 T5943 c:collection1 C2188 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 1212198 T5943 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 0 [junit4] 2> 1212202 T5923 c:collection1 C2186 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212202 T5923 c:collection1 C2186 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d0ec8be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@659d69e1),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d0ec8be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@659d69e1),segFN=segments_3,generation=3} [junit4] 2> 1212202 T5923 c:collection1 C2186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1212202 T5923 c:collection1 C2186 oass.SolrIndexSearcher.<init> Opening Searcher@43b530f1[collection1] main [junit4] 2> 1212203 T5923 c:collection1 C2186 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212204 T5927 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@43b530f1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1212205 T5923 c:collection1 C2186 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 4 [junit4] 2> 1212209 T5936 c:collection1 C2187 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212209 T5936 c:collection1 C2187 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_4,generation=4} [junit4] 2> 1212209 T5936 c:collection1 C2187 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4 [junit4] 2> 1212209 T5936 c:collection1 C2187 oass.SolrIndexSearcher.<init> Opening Searcher@30e87578[collection1] main [junit4] 2> 1212210 T5936 c:collection1 C2187 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212211 T5939 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30e87578[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1212217 T5936 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 9 [junit4] 2> 1212224 T5948 c:collection1 C2188 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1212225 T5948 c:collection1 C2188 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2c00f4cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72c793a5),segFN=segments_4,generation=4} [junit4] 2> 1212225 T5948 c:collection1 C2188 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4 [junit4] 2> 1212226 T5948 c:collection1 C2188 oass.SolrIndexSearcher.<init> Opening Searcher@3feb6846[collection1] main [junit4] 2> 1212226 T5948 c:collection1 C2188 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1212228 T5951 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3feb6846[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1212228 T5948 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&softCommit=false&version=2&commit=true&waitSearcher=true} {commit=} 0 4 [junit4] 2> 1212233 T5934 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[0]} 0 1 [junit4] 2> 1212237 T5947 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1]} 0 1 [junit4] 2> 1212242 T5932 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2]} 0 1 [junit4] 2> 1212246 T5946 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2]} 0 1 [junit4] 2> 1212249 T5937 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3]} 0 0 [junit4] 2> 1212253 T5933 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4]} 0 1 [junit4] 2> 1212256 T5936 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5]} 0 0 [junit4] 2> 1212260 T5934 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6]} 0 1 [junit4] 2> 1212263 T5944 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6]} 0 0 [junit4] 2> 1212267 T5935 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7]} 0 0 [junit4] 2> 1212284 T5932 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8]} 0 3 [junit4] 2> 1212288 T5937 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9]} 0 0 [junit4] 2> 1212292 T5933 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10]} 0 0 [junit4] 2> 1212295 T5936 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11]} 0 0 [junit4] 2> 1212299 T5949 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12]} 0 0 [junit4] 2> 1212303 T5943 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13]} 0 1 [junit4] 2> 1212306 T5934 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14]} 0 0 [junit4] 2> 1212311 T5948 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15]} 0 1 [junit4] 2> 1212315 T5947 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16]} 0 0 [junit4] 2> 1212318 T5935 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17]} 0 0 [junit4] 2> 1212322 T5946 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18]} 0 1 [junit4] 2> 1212325 T5944 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19]} 0 0 [junit4] 2> 1212328 T5949 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20]} 0 0 [junit4] 2> 1212332 T5932 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21]} 0 0 [junit4] 2> 1212336 T5943 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22]} 0 1 [junit4] 2> 1212339 T5937 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[23]} 0 0 [junit4] 2> 1212343 T5948 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[24]} 0 1 [junit4] 2> 1212346 T5947 c:collection1 C2188 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[25]} 0 0 [junit4] 2> 1212355 T5933 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[26]} 0 1 [junit4] 2> 1212358 T5936 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[27]} 0 0 [junit4] 2> 1302407 T5916 oas.SolrTestCaseJ4.tearDown ###Ending test [junit4] 2> 1302408 T5932 c:collection1 C2187 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[28]} 0 0 [junit4] 2> 1302421 T5916 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=122740122 [junit4] 2> 1302423 T5916 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6a12c317 [junit4] 2> 1302423 T5916 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=0} [junit4] 2> 1302424 T5916 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState [junit4] 2> 1302424 T5916 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 1302435 T5916 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser [junit4] 2> 1302436 T5916 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 1302446 T5916 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 1302447 T5916 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data;done=false>>] [junit4] 2> 1302447 T5916 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data [junit4] 2> 1302448 T5916 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data/index;done=false>>] [junit4] 2> 1302449 T5916 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 1302451 T5916 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> 1302657 T5916 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=710512921 [junit4] 2> 1302658 T5916 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@316769fb [junit4] 2> 1302659 T5916 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=18,adds=18,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=19,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=0} [junit4] 2> 1302659 T5916 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState [junit4] 2> 1302659 T5916 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 1302659 T5916 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser [junit4] 2> ASYNC NEW_CORE C2189 name=collection1 org.apache.solr.core.SolrCore@316769fb [junit4] 2> 1302663 T5916 C2189 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_4,generation=4} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6865dc1d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77562976),segFN=segments_5,generation=5} [junit4] 2> 1302663 T5916 C2189 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5 [junit4] 2> 1302664 T5916 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 1302671 T5916 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 1302671 T5916 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data;done=false>>] [junit4] 2> 1302671 T5916 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 1302672 T5916 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard0/cores/collection1/data/index;done=false>>] [junit4] 2> 1302672 T5916 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/ [...truncated too long message...] ng to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data/index;done=false>>] [junit4] 2> 1302892 T5916 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestHighlightDedupGrouping 45E3AC02B9E50801-001/tempDir-001/shard1/cores/collection1/data/index [junit4] 2> 1302894 T5916 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestHighlightDedupGrouping -Dtests.method=test -Dtests.seed=45E3AC02B9E50801 -Dtests.slow=true -Dtests.locale=el_CY -Dtests.timezone=America/Eirunepe -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 95.0s 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:60145//collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([45E3AC02B9E50801:CDB793D8171965F9]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:567) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) [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.read(SocketInputStream.java:152) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:122) [junit4] > at sun.security.ssl.InputRecord.readFully(InputRecord.java:442) [junit4] > at sun.security.ssl.InputRecord.read(InputRecord.java:480) [junit4] > at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:934) [junit4] > at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:891) [junit4] > at sun.security.ssl.AppInputStream.read(AppInputStream.java:102) [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:463) [junit4] > ... 50 more [junit4] 2> 1303076 T5916 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 45E3AC02B9E50801-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50), sim=DefaultSimilarity, locale=el_CY, timezone=America/Eirunepe [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_76 (64-bit)/cpus=3,threads=1,free=233984176,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [ReturnFieldsTest, DistributedQueryComponentOptimizationTest, HighlighterMaxOffsetTest, TermsComponentTest, DirectSolrSpellCheckerTest, TestRangeQuery, TestJettySolrRunner, PrimitiveFieldTypeTest, PolyFieldTest, PluginInfoTest, SolrIndexConfigTest, SignatureUpdateProcessorFactoryTest, TestOverriddenPrefixQueryForCustomFieldType, IndexBasedSpellCheckerTest, DirectUpdateHandlerOptimizeTest, FileUtilsTest, CopyFieldTest, TestRestoreCore, DateMathParserTest, DistributedTermsComponentTest, TestDFRSimilarityFactory, TestSearchPerf, SpellCheckCollatorTest, OpenCloseCoreStressTest, TestRestManager, TestCopyFieldCollectionResource, TestSimpleTrackingShardHandler, TestSolrXml, TestConfig, DeleteReplicaTest, TestDefaultStatsCache, TestReloadAndDeleteDocs, TestSolrQueryParserDefaultOperatorResource, TestRealTimeGet, BlockDirectoryTest, DistribDocExpirationUpdateProcessorTest, DisMaxRequestHandlerTest, TestEmbeddedSolrServerConstructors, TestShardHandlerFactory, SpellingQueryConverterTest, TestFiltering, ExitableDirectoryReaderTest, DistribJoinFromCollectionTest, LoggingHandlerTest, TestStressUserVersions, TestCloudManagedSchema, TestFastOutputStream, TestMergePolicyConfig, TestReplicaProperties, TestAnalyzeInfixSuggestions, TestDistributedGrouping, SaslZkACLProviderTest, ActionThrottleTest, TestLeaderElectionZkExpiry, CoreAdminCreateDiscoverTest, TestQuerySenderNoQuery, SpatialFilterTest, DistribCursorPagingTest, IndexSchemaTest, LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestSimpleQParserPlugin, TestPseudoReturnFields, TestValueSourceCache, HighlighterTest, AddSchemaFieldsUpdateProcessorFactoryTest, SearchHandlerTest, OverseerStatusTest, TestReversedWildcardFilterFactory, AsyncMigrateRouteKeyTest, DistributedFacetPivotSmallTest, DistributedMLTComponentTest, VMParamsZkACLAndCredentialsProvidersTest, TestClassNameShortening, ZkSolrClientTest, TestJsonRequest, TermVectorComponentTest, TestHighFrequencyDictionaryFactory, TestLuceneMatchVersion, OverriddenZkACLAndCredentialsProvidersTest, DistributedFacetPivotLargeTest, TestZkChroot, XsltUpdateRequestHandlerTest, TestExpandComponent, TestSolrJ, TestSolrConfigHandlerConcurrent, TestRecoveryHdfs, TestStandardQParsers, CollectionsAPIDistributedZkTest, DistributedDebugComponentTest, JsonLoaderTest, BufferStoreTest, UUIDUpdateProcessorFallbackTest, TestHighlightDedupGrouping] [junit4] Completed [203/482] on J1 in 95.03s, 1 test, 1 error <<< FAILURES! [...truncated 881 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: 482 suites, 1914 tests, 1 error, 55 ignored (21 assumptions) Total time: 84 minutes 20 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC 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]
