Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2177/ Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.TestHighlightDedupGrouping.test Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:64897/dbpoe/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:64897/dbpoe/collection1 at __randomizedtesting.SeedInfo.seed([79A1115B71854DEA:F1F52E81DF792012]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:174) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:139) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:153) at org.apache.solr.TestHighlightDedupGrouping.addDoc(TestHighlightDedupGrouping.java:122) at org.apache.solr.TestHighlightDedupGrouping.randomizedTest(TestHighlightDedupGrouping.java:96) at org.apache.solr.TestHighlightDedupGrouping.test(TestHighlightDedupGrouping.java:42) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java: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:466) ... 50 more Build Log: [...truncated 9716 lines...] [junit4] Suite: org.apache.solr.TestHighlightDedupGrouping [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/init-core-data-001 [junit4] 2> 382482 T1522 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 382483 T1522 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /dbpoe/ [junit4] 2> 383330 T1522 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1 [junit4] 2> 383454 T1522 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 384099 T1522 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 384117 T1522 oejs.AbstractConnector.doStart Started [email protected]:64889 [junit4] 2> 384136 T1522 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=64889, hostContext=/dbpoe, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores} [junit4] 2> 384139 T1522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5bdf59bd [junit4] 2> 384139 T1522 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/' [junit4] 2> 384287 T1522 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/solr.xml [junit4] 2> 384393 T1522 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores [junit4] 2> 384395 T1522 oasc.CoreContainer.<init> New CoreContainer 418963305 [junit4] 2> 384395 T1522 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/] [junit4] 2> 384395 T1522 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/lib [junit4] 2> 384397 T1522 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/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/lib). [junit4] 2> 384411 T1522 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 384419 T1522 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 384424 T1522 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 384424 T1522 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 384425 T1522 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 384434 T1522 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores [junit4] 2> 384436 T1522 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {loadOnStartup=true, config=solrconfig.xml, schema=schema.xml, transient=false, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1/, name=collection1, configSet=collection1, dataDir=data/} [junit4] 2> 384437 T1522 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1/ [junit4] 2> 384437 T1522 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 384440 T1532 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 384441 T1532 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> 384442 T1532 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> 384524 T1532 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 384556 T1532 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 384630 T1532 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 384632 T1532 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> 384650 T1532 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 385180 T1532 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 385220 T1532 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 385226 T1532 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 385241 T1532 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 385245 T1532 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 385249 T1532 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 385250 T1532 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 385250 T1532 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 385251 T1532 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 385251 T1532 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 385251 T1532 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 385251 T1532 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> 385251 T1532 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 385252 T1532 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=[null] [junit4] 2> 385252 T1532 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@30afd58d [junit4] 2> 385252 T1532 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1/data [junit4] 2> 385255 T1532 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/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 385256 T1532 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 385256 T1532 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 385256 T1532 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7226098058994882] [junit4] 2> 385257 T1532 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c91b77c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a54cf5),segFN=segments_1,generation=1} [junit4] 2> 385257 T1532 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 385263 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 385264 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 385264 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 385264 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 385264 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 385264 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 385265 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 385265 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 385265 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 385266 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 385266 T1532 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 385266 T1532 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 385286 T1532 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 385291 T1532 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 385295 T1532 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 385297 T1532 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 385311 T1532 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 385337 T1532 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /get,/debug/dump,/admin/segments,/update/csv,/admin/file,/admin/ping,/admin/plugins,spellCheckWithWordbreak_Direct,spellCheckCompRH1,/admin/threads,defaults,/terms,/config,standard,tvrh,/admin/logging,/admin/luke,spellCheckWithWordbreak,lazy,/search-facet-invariants,/mlt,dismax,/admin/properties,mltrh,/update/json,mock,/replication,/admin/system,/update,/admin/mbeans,/update/json/docs,/search-facet-def,spellCheckCompRH_Direct,/schema,spellCheckCompRH [junit4] 2> 385339 T1532 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 385341 T1532 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 385343 T1532 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 385343 T1532 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 385354 T1532 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 385354 T1532 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 385360 T1532 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8300947324802269] [junit4] 2> 385361 T1532 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c91b77c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a54cf5),segFN=segments_1,generation=1} [junit4] 2> 385361 T1532 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 385362 T1532 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@17f70082[collection1] main [junit4] 2> 385363 T1532 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 385363 T1532 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 385363 T1532 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 385363 T1532 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 385393 T1532 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 385473 T1532 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 385473 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 385474 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 385475 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 385475 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 385476 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 385478 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 385479 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 385480 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 385482 T1532 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 385483 T1533 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 385484 T1522 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 385484 T1522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 385485 T1533 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17f70082[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 387320 T1522 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 387355 T1522 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 387407 T1522 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 387415 T1522 oejs.AbstractConnector.doStart Started [email protected]:64897 [junit4] 2> 387419 T1522 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostPort=64897, hostContext=/dbpoe, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores} [junit4] 2> 387424 T1522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5bdf59bd [junit4] 2> 387424 T1522 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/' [junit4] 2> 387503 T1522 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/solr.xml [junit4] 2> 387549 T1522 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores [junit4] 2> 387550 T1522 oasc.CoreContainer.<init> New CoreContainer 756430729 [junit4] 2> 387550 T1522 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/] [junit4] 2> 387550 T1522 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/lib [junit4] 2> 387551 T1522 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/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/lib). [junit4] 2> 387567 T1522 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 387614 T1522 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 387619 T1522 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 387619 T1522 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 387620 T1522 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 387680 T1522 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores [junit4] 2> 387682 T1522 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {configSet=collection1, transient=false, dataDir=data/, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1, loadOnStartup=true, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1/, config=solrconfig.xml, schema=schema.xml, name=collection1} [junit4] 2> 387682 T1522 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 387682 T1522 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 387683 T1544 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 387686 T1544 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> 387686 T1544 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> 387758 T1544 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 387796 T1544 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 387888 T1544 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 387889 T1544 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> 387901 T1544 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 388663 T1544 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 388712 T1544 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 388716 T1544 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 388738 T1544 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 388745 T1544 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 388761 T1544 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 388778 T1544 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 388778 T1544 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 388779 T1544 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 388780 T1544 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 388780 T1544 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 388781 T1544 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> 388781 T1544 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 388782 T1544 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=[null] [junit4] 2> 388782 T1544 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@30afd58d [junit4] 2> 388783 T1544 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 388787 T1544 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/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 388787 T1544 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 388788 T1544 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 388789 T1544 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7226098058994882] [junit4] 2> 388793 T1544 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7778b32a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@153ac7ad),segFN=segments_1,generation=1} [junit4] 2> 388794 T1544 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 388818 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 388819 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 388819 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 388820 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 388822 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 388823 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 388823 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 388827 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 388827 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 388828 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 388829 T1544 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 388829 T1544 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 388888 T1544 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 388901 T1544 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 388906 T1544 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 388910 T1544 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 388923 T1544 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 388932 T1544 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /get,/debug/dump,/admin/segments,/update/csv,/admin/file,/admin/ping,/admin/plugins,spellCheckWithWordbreak_Direct,spellCheckCompRH1,/admin/threads,defaults,/terms,/config,standard,tvrh,/admin/logging,/admin/luke,spellCheckWithWordbreak,lazy,/search-facet-invariants,/mlt,dismax,/admin/properties,mltrh,/update/json,mock,/replication,/admin/system,/update,/admin/mbeans,/update/json/docs,/search-facet-def,spellCheckCompRH_Direct,/schema,spellCheckCompRH [junit4] 2> 388934 T1544 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 388936 T1544 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 388944 T1544 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 388945 T1544 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 388947 T1544 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 388948 T1544 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 388948 T1544 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8300947324802269] [junit4] 2> 388949 T1544 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7778b32a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@153ac7ad),segFN=segments_1,generation=1} [junit4] 2> 388949 T1544 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 388950 T1544 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@659b1f9d[collection1] main [junit4] 2> 388950 T1544 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 388951 T1544 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 388951 T1544 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 388951 T1544 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 388962 T1544 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 389060 T1544 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 389061 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 389062 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 389063 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 389063 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 389064 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 389065 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 389066 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 389067 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 389068 T1545 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 389077 T1545 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@659b1f9d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 389081 T1544 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 389086 T1522 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 389087 T1522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 390509 T1522 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 390538 T1522 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 390589 T1522 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 390609 T1522 oejs.AbstractConnector.doStart Started [email protected]:64905 [junit4] 2> 390610 T1522 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/dbpoe, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores, hostPort=64905} [junit4] 2> 390623 T1522 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5bdf59bd [junit4] 2> 390624 T1522 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/' [junit4] 2> 390663 T1522 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/solr.xml [junit4] 2> 390698 T1522 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores [junit4] 2> 390698 T1522 oasc.CoreContainer.<init> New CoreContainer 1993259704 [junit4] 2> 390699 T1522 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/] [junit4] 2> 390700 T1522 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/lib [junit4] 2> 390700 T1522 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/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/lib). [junit4] 2> 390715 T1522 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 390728 T1522 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 390735 T1522 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 390736 T1522 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 390736 T1522 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 390770 T1522 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores [junit4] 2> 390779 T1522 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1/, configSet=collection1, dataDir=data/, schema=schema.xml, transient=false} [junit4] 2> 390780 T1522 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1/ [junit4] 2> 390781 T1522 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 390786 T1556 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 390788 T1556 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> 390788 T1556 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> 390842 T1556 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 390869 T1556 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 390952 T1556 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 390954 T1556 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> 390968 T1556 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 391357 T1556 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 391376 T1556 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 391378 T1556 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 391389 T1556 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 391393 T1556 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 391399 T1556 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 391399 T1556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 391399 T1556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 391400 T1556 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 391400 T1556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 391400 T1556 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 391401 T1556 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> 391401 T1556 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 391401 T1556 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=[null] [junit4] 2> 391401 T1556 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@30afd58d [junit4] 2> 391404 T1556 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1/data [junit4] 2> 391405 T1556 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/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1/data/index/ [junit4] 2> 391405 T1556 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 391409 T1556 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1/data/index [junit4] 2> 391409 T1556 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7226098058994882] [junit4] 2> 391412 T1556 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3e144aa4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263074d3),segFN=segments_1,generation=1} [junit4] 2> 391412 T1556 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 391433 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 391433 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 391434 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 391434 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 391435 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 391435 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 391436 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 391437 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 391437 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 391438 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 391439 T1556 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 391439 T1556 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 391459 T1556 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 391462 T1556 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 391466 T1556 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 391471 T1556 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 391483 T1556 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 391490 T1556 c:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /get,/debug/dump,/admin/segments,/update/csv,/admin/file,/admin/ping,/admin/plugins,spellCheckWithWordbreak_Direct,spellCheckCompRH1,/admin/threads,defaults,/terms,/config,standard,tvrh,/admin/logging,/admin/luke,spellCheckWithWordbreak,lazy,/search-facet-invariants,/mlt,dismax,/admin/properties,mltrh,/update/json,mock,/replication,/admin/system,/update,/admin/mbeans,/update/json/docs,/search-facet-def,spellCheckCompRH_Direct,/schema,spellCheckCompRH [junit4] 2> 391491 T1556 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 391492 T1556 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 391494 T1556 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 391494 T1556 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 391495 T1556 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 391495 T1556 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 391496 T1556 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8300947324802269] [junit4] 2> 391502 T1556 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3e144aa4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263074d3),segFN=segments_1,generation=1} [junit4] 2> 391502 T1556 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 391503 T1556 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@40cdfb2b[collection1] main [junit4] 2> 391503 T1556 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 391503 T1556 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 391504 T1556 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 391504 T1556 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 391525 T1556 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 391656 T1556 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 391657 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 391658 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 391658 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 391658 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 391659 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 391660 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 391660 T1556 c:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 391661 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 391662 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 391662 T1522 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 391662 T1522 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 391663 T1557 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 391672 T1522 oas.SolrTestCaseJ4.setUp ###Starting test [junit4] 2> 391677 T1557 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40cdfb2b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> ASYNC NEW_CORE C347 name=collection1 org.apache.solr.core.SolrCore@600248c1 [junit4] 2> 392067 T1529 c:collection1 C347 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499410045384785920)} 0 3 [junit4] 2> ASYNC NEW_CORE C348 name=collection1 org.apache.solr.core.SolrCore@61ba62f2 [junit4] 2> 392180 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499410045483352064)} 0 22 [junit4] 2> ASYNC NEW_CORE C349 name=collection1 org.apache.solr.core.SolrCore@30cc443a [junit4] 2> 392345 T1554 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499410045674192896)} 0 10 [junit4] 2> 392364 T1530 c:collection1 C347 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 392365 T1530 c:collection1 C347 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 392366 T1530 c:collection1 C347 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 392368 T1530 c:collection1 C347 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 392368 T1530 c:collection1 C347 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 4 [junit4] 2> 392388 T1540 c:collection1 C348 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 392388 T1540 c:collection1 C348 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 392401 T1540 c:collection1 C348 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 392402 T1540 c:collection1 C348 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 392403 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 16 [junit4] 2> 392419 T1553 c:collection1 C349 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 392420 T1553 c:collection1 C349 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 392420 T1553 c:collection1 C349 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 392422 T1553 c:collection1 C349 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 392422 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 3 [junit4] 2> 392441 T1542 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[1 (1499410045770661888)]} 0 9 [junit4] 2> 392470 T1538 c:collection1 C348 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 392511 T1538 c:collection1 C348 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7778b32a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@153ac7ad),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7778b32a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@153ac7ad),segFN=segments_2,generation=2} [junit4] 2> 392516 T1538 c:collection1 C348 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 392543 T1538 c:collection1 C348 oass.SolrIndexSearcher.<init> Opening Searcher@9c241d7[collection1] main [junit4] 2> 392549 T1545 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9c241d7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C1)))} [junit4] 2> 392554 T1538 c:collection1 C348 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 392554 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 84 [junit4] 2> 392577 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[1 (1499410045911171072)]} 0 11 [junit4] 2> 392586 T1551 c:collection1 C349 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 392596 T1551 c:collection1 C349 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3e144aa4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263074d3),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3e144aa4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263074d3),segFN=segments_2,generation=2} [junit4] 2> 392598 T1551 c:collection1 C349 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 392600 T1551 c:collection1 C349 oass.SolrIndexSearcher.<init> Opening Searcher@37161fe[collection1] main [junit4] 2> 392602 T1551 c:collection1 C349 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 392603 T1557 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37161fe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C1)))} [junit4] 2> 392604 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 19 [junit4] 2> 392807 T1551 c:collection1 C349 oasc.SolrCore.execute [collection1] webapp=/dbpoe path=/select params={group.field=id_s1&shards.purpose=2048&group=true&group.limit=2&distrib=false&version=2&isShard=true&start=0&hl.fl=id_s1&shard.url=https://127.0.0.1:64905/dbpoe/collection1&hl=false&q=id_s1:1&fl=id,score&wt=javabin&NOW=1429948850591&rows=10&group.distributed.first=true} status=0 QTime=76 [junit4] 2> 392807 T1541 c:collection1 C348 oasc.SolrCore.execute [collection1] webapp=/dbpoe path=/select params={group.field=id_s1&shards.purpose=2048&group=true&group.limit=2&distrib=false&version=2&isShard=true&start=0&hl.fl=id_s1&shard.url=https://127.0.0.1:64897/dbpoe/collection1&hl=false&q=id_s1:1&fl=id,score&wt=javabin&NOW=1429948850591&rows=10&group.distributed.first=true} status=0 QTime=95 [junit4] 2> 392949 T1555 c:collection1 C349 oasc.SolrCore.execute [collection1] webapp=/dbpoe path=/select params={group.field=id_s1&group.topgroups.id_s1=1&shards.purpose=4&group=true&group.limit=2&distrib=false&version=2&isShard=true&start=0&hl.fl=id_s1&shard.url=https://127.0.0.1:64905/dbpoe/collection1&hl=false&q=id_s1:1&fl=id,score&wt=javabin&group.distributed.second=true&NOW=1429948850591&rows=10} status=0 QTime=53 [junit4] 2> 392951 T1543 c:collection1 C348 oasc.SolrCore.execute [collection1] webapp=/dbpoe path=/select params={group.field=id_s1&group.topgroups.id_s1=1&shards.purpose=4&group=true&group.limit=2&distrib=false&version=2&isShard=true&start=0&hl.fl=id_s1&shard.url=https://127.0.0.1:64897/dbpoe/collection1&hl=false&q=id_s1:1&fl=id,score&wt=javabin&group.distributed.second=true&NOW=1429948850591&rows=10} status=0 QTime=53 [junit4] 2> 393261 T1543 c:collection1 C348 oasc.SolrCore.execute [collection1] webapp=/dbpoe path=/select params={group.field=id_s1&shards.purpose=192&group.limit=2&distrib=false&ids=1&version=2&isShard=true&hl.fl=id_s1&shard.url=https://127.0.0.1:64897/dbpoe/collection1&hl=true&q=id_s1:1&wt=javabin&NOW=1429948850591} status=0 QTime=238 [junit4] 2> 393288 T1550 c:collection1 C349 oasc.SolrCore.execute [collection1] webapp=/dbpoe path=/select params={group.field=id_s1&shards.purpose=192&group.limit=2&distrib=false&ids=1&version=2&isShard=true&hl.fl=id_s1&shard.url=https://127.0.0.1:64905/dbpoe/collection1&hl=true&q=id_s1:1&wt=javabin&NOW=1429948850591} status=0 QTime=267 [junit4] 2> 393299 T1540 c:collection1 C348 oasc.SolrCore.execute [collection1] webapp=/dbpoe path=/select params={group.field=id_s1&group=true&q=id_s1:1&wt=javabin&group.limit=2&version=2&hl.fl=id_s1&shards=https://127.0.0.1:64897/dbpoe/collection1,https://127.0.0.1:64905/dbpoe/collection1&hl=true} status=0 QTime=689 [junit4] 2> 393325 T1526 c:collection1 C347 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499410046704943104)} 0 2 [junit4] 2> 393341 T1539 c:collection1 C348 oass.SolrIndexSearcher.<init> Opening Searcher@51539622[collection1] realtime [junit4] 2> 393341 T1539 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499410046713331712)} 0 10 [junit4] 2> 393355 T1551 c:collection1 C349 oass.SolrIndexSearcher.<init> Opening Searcher@10d5d67f[collection1] realtime [junit4] 2> 393356 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1499410046734303232)} 0 5 [junit4] 2> 393361 T1530 c:collection1 C347 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 393362 T1530 c:collection1 C347 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 393372 T1530 c:collection1 C347 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 393375 T1530 c:collection1 C347 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 393375 T1530 c:collection1 C347 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 14 [junit4] 2> 393382 T1541 c:collection1 C348 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 393389 T1541 c:collection1 C348 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7778b32a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@153ac7ad),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7778b32a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@153ac7ad),segFN=segments_3,generation=3} [junit4] 2> 393389 T1541 c:collection1 C348 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 393398 T1541 c:collection1 C348 oass.SolrIndexSearcher.<init> Opening Searcher@3c32d970[collection1] main [junit4] 2> 393400 T1541 c:collection1 C348 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 393402 T1545 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c32d970[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 393403 T1541 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 22 [junit4] 2> 393410 T1553 c:collection1 C349 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 393410 T1553 c:collection1 C349 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3e144aa4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263074d3),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3e144aa4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263074d3),segFN=segments_3,generation=3} [junit4] 2> 393411 T1553 c:collection1 C349 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 393411 T1553 c:collection1 C349 oass.SolrIndexSearcher.<init> Opening Searcher@456f012a[collection1] main [junit4] 2> 393415 T1553 c:collection1 C349 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 393416 T1557 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@456f012a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 393418 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={waitSearcher=true&wt=javabin&version=2&commit=true&softCommit=false} {commit=} 0 9 [junit4] 2> 393437 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[0 (1499410046812946432)]} 0 12 [junit4] 2> 393446 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[1 (1499410046831820800)]} 0 4 [junit4] 2> 393454 T1543 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[2 (1499410046841257984)]} 0 2 [junit4] 2> 393460 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[3 (1499410046848598016)]} 0 1 [junit4] 2> 393467 T1539 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[4 (1499410046854889472)]} 0 1 [junit4] 2> 393491 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[5 (1499410046880055296)]} 0 1 [junit4] 2> 393497 T1542 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[6 (1499410046887395328)]} 0 1 [junit4] 2> 393515 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[7 (1499410046897881088)]} 0 8 [junit4] 2> 393525 T1541 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[8 (1499410046916755456)]} 0 1 [junit4] 2> 393531 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[9 (1499410046923046912)]} 0 0 [junit4] 2> 393539 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[10 (1499410046931435520)]} 0 1 [junit4] 2> 393547 T1554 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[11 (1499410046939824128)]} 0 1 [junit4] 2> 393553 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[12 (1499410046946115584)]} 0 0 [junit4] 2> 393560 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[13 (1499410046953455616)]} 0 0 [junit4] 2> 393566 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[14 (1499410046958698496)]} 0 1 [junit4] 2> 393580 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[15 (1499410046974427136)]} 0 1 [junit4] 2> 393586 T1554 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[16 (1499410046980718592)]} 0 1 [junit4] 2> 393592 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[17 (1499410046987010048)]} 0 1 [junit4] 2> 393600 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[18 (1499410046995398656)]} 0 0 [junit4] 2> 393609 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[19 (1499410047004835840)]} 0 1 [junit4] 2> 393618 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[20 (1499410047014273024)]} 0 0 [junit4] 2> 393624 T1543 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[21 (1499410047020564480)]} 0 0 [junit4] 2> 393630 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[22 (1499410047026855936)]} 0 1 [junit4] 2> 393640 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[22 (1499410047036293120)]} 0 1 [junit4] 2> 393647 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[23 (1499410047043633152)]} 0 1 [junit4] 2> 393655 T1539 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[24 (1499410047052021760)]} 0 1 [junit4] 2> 393663 T1554 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[24 (1499410047060410368)]} 0 1 [junit4] 2> 393678 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[25 (1499410047077187584)]} 0 1 [junit4] 2> 393683 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[26 (1499410047082430464)]} 0 0 [junit4] 2> 393689 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[27 (1499410047087673344)]} 0 1 [junit4] 2> 393694 T1543 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[28 (1499410047093964800)]} 0 0 [junit4] 2> 393699 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[29 (1499410047099207680)]} 0 1 [junit4] 2> 393705 T1539 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[30 (1499410047104450560)]} 0 1 [junit4] 2> 393710 T1541 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[31 (1499410047110742016)]} 0 1 [junit4] 2> 393715 T1542 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[32 (1499410047115984896)]} 0 1 [junit4] 2> 393721 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[33 (1499410047122276352)]} 0 0 [junit4] 2> 393726 T1543 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[34 (1499410047127519232)]} 0 0 [junit4] 2> 393734 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[35 (1499410047135907840)]} 0 0 [junit4] 2> 393739 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[36 (1499410047141150720)]} 0 1 [junit4] 2> 393745 T1539 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[37 (1499410047146393600)]} 0 1 [junit4] 2> 393750 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[38 (1499410047151636480)]} 0 1 [junit4] 2> 393754 T1554 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[39 (1499410047156879360)]} 0 0 [junit4] 2> 393758 T1541 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[40 (1499410047161073664)]} 0 0 [junit4] 2> 393763 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[41 (1499410047165267968)]} 0 2 [junit4] 2> 393767 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[42 (1499410047170510848)]} 0 0 [junit4] 2> 393775 T1542 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[43 (1499410047177850880)]} 0 1 [junit4] 2> 393791 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[44 (1499410047194628096)]} 0 2 [junit4] 2> 393797 T1543 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[45 (1499410047200919552)]} 0 1 [junit4] 2> 393804 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[46 (1499410047208259584)]} 0 1 [junit4] 2> 393815 T1541 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[47 (1499410047219793920)]} 0 1 [junit4] 2> 393852 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[48 (1499410047259639808)]} 0 4 [junit4] 2> 393868 T1542 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[49 (1499410047275368448)]} 0 2 [junit4] 2> 393879 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[50 (1499410047287951360)]} 0 0 [junit4] 2> 393891 T1543 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[51 (1499410047299485696)]} 0 1 [junit4] 2> 393901 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[52 (1499410047309971456)]} 0 1 [junit4] 2> 393906 T1554 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[53 (1499410047315214336)]} 0 1 [junit4] 2> 393923 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[54 (1499410047333040128)]} 0 1 [junit4] 2> 393932 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[55 (1499410047342477312)]} 0 1 [junit4] 2> 393939 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[56 (1499410047349817344)]} 0 1 [junit4] 2> 393947 T1539 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[57 (1499410047358205952)]} 0 1 [junit4] 2> 393953 T1541 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[58 (1499410047365545984)]} 0 1 [junit4] 2> 393963 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[59 (1499410047374983168)]} 0 1 [junit4] 2> 393971 T1542 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[60 (1499410047384420352)]} 0 1 [junit4] 2> 393977 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[61 (1499410047389663232)]} 0 1 [junit4] 2> 393988 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[61 (1499410047402246144)]} 0 0 [junit4] 2> 393998 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[62 (1499410047411683328)]} 0 1 [junit4] 2> 394008 T1543 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[63 (1499410047422169088)]} 0 1 [junit4] 2> 394027 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[64 (1499410047443140608)]} 0 1 [junit4] 2> 394045 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[65 (1499410047462014976)]} 0 1 [junit4] 2> 394051 T1539 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[66 (1499410047467257856)]} 0 1 [junit4] 2> 394060 T1541 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[67 (1499410047477743616)]} 0 1 [junit4] 2> 394068 T1542 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[68 (1499410047486132224)]} 0 2 [junit4] 2> 394077 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[69 (1499410047494520832)]} 0 1 [junit4] 2> 394094 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[70 (1499410047512346624)]} 0 1 [junit4] 2> 394101 T1538 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[71 (1499410047520735232)]} 0 1 [junit4] 2> 394109 T1540 c:collection1 C348 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[72 (1499410047528075264)]} 0 1 [junit4] 2> 394119 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[73 (1499410047539609600)]} 0 1 [junit4] 2> 394129 T1550 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[74 (1499410047550095360)]} 0 0 [junit4] 2> 394139 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[75 (1499410047560581120)]} 0 1 [junit4] 2> 394148 T1551 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[76 (1499410047570018304)]} 0 1 [junit4] 2> 394154 T1552 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[77 (1499410047575261184)]} 0 1 [junit4] 2> 394160 T1554 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[78 (1499410047582601216)]} 0 0 [junit4] 2> 394172 T1553 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/dbpoe path=/update params={wt=javabin&version=2} {add=[79 (1499410047594135552)]} 0 2 [junit4] 2> 394177 T1555 c:collection1 C349 oasup.LogUpdateProcessor.finish [collection1] webapp=/ [...truncated too long message...] ;done=false>>] [junit4] 2> 492909 T1522 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001/tempDir-001/shard1/cores/collection1/data/index [junit4] 2> 492910 T1522 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/dbpoe,null} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestHighlightDedupGrouping -Dtests.method=test -Dtests.seed=79A1115B71854DEA -Dtests.slow=true -Dtests.locale=sr_ME -Dtests.timezone=Asia/Tokyo -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 111s J0 | TestHighlightDedupGrouping.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:64897/dbpoe/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([79A1115B71854DEA:F1F52E81DF792012]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:174) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:139) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:153) [junit4] > at org.apache.solr.TestHighlightDedupGrouping.addDoc(TestHighlightDedupGrouping.java:122) [junit4] > at org.apache.solr.TestHighlightDedupGrouping.randomizedTest(TestHighlightDedupGrouping.java:96) [junit4] > at org.apache.solr.TestHighlightDedupGrouping.test(TestHighlightDedupGrouping.java:42) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.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:466) [junit4] > ... 50 more [junit4] 2> 493075 T1522 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/J0/temp/solr.TestHighlightDedupGrouping 79A1115B71854DEA-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {timestamp=PostingsFormat(name=LuceneVarGapDocFreqInterval), shard_i1=Lucene50(blocksize=128), range_facet_i_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), group_ti1=PostingsFormat(name=LuceneFixedGap), range_facet_l=PostingsFormat(name=LuceneVarGapDocFreqInterval), id_s1=PostingsFormat(name=LuceneVarGapDocFreqInterval), intDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=LuceneFixedGap), range_facet_l_dv=Lucene50(blocksize=128)}, docValues:{timestamp=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Asserting), range_facet_l_dv=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=sr_ME, timezone=Asia/Tokyo [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_76 (64-bit)/cpus=3,threads=1,free=182983904,total=315097088 [junit4] 2> NOTE: All tests run in this JVM: [CursorPagingTest, EnumFieldTest, DefaultValueUpdateProcessorTest, RequiredFieldsTest, TestWriterPerf, DocumentAnalysisRequestHandlerTest, JSONWriterTest, TestSchemaResource, OverriddenZkACLAndCredentialsProvidersTest, TestSweetSpotSimilarityFactory, TestFaceting, TestJsonRequest, HdfsRecoveryZkTest, CircularListTest, XmlUpdateRequestHandlerTest, ClusterStateTest, CustomCollectionTest, TestReplicationHandler, TestCollapseQParserPlugin, HdfsBasicDistributedZk2Test, DistanceUnitsTest, TestHighlightDedupGrouping] [junit4] Completed [35/484] on J0 in 110.64s, 1 test, 1 error <<< FAILURES! [...truncated 1407 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: 484 suites, 1946 tests, 1 error, 59 ignored (24 assumptions) Total time: 79 minutes 30 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts ERROR: Failed to archive artifacts: **/*.events,heapdumps/**,**/hs_err_pid* java.io.IOException: Failed to extract /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/transfer of 2 files at hudson.FilePath.readFromTar(FilePath.java:2299) at hudson.FilePath.copyRecursiveTo(FilePath.java:2208) at jenkins.model.StandardArtifactManager.archive(StandardArtifactManager.java:61) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:219) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:74) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:761) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:721) at hudson.model.Build$BuildExecution.post2(Build.java:183) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:670) at hudson.model.Run.execute(Run.java:1766) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:98) at hudson.model.Executor.run(Executor.java:374) Caused by: java.io.IOException: Truncated TAR archive at org.apache.commons.compress.archivers.tar.TarArchiveInputStream.read(TarArchiveInputStream.java:614) at java.io.InputStream.read(InputStream.java:101) at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1792) at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1769) at org.apache.commons.io.IOUtils.copy(IOUtils.java:1744) at hudson.util.IOUtils.copy(IOUtils.java:40) at hudson.FilePath.readFromTar(FilePath.java:2289) ... 13 more 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]
