Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1914/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed. REGRESSION: org.apache.solr.TestDistributedGrouping.testDistribSearch Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:51138 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:51138 at __randomizedtesting.SeedInfo.seed([2D26F091FD6BBC5A:ACC07E898A34DC66]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:581) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211) at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116) at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102) at org.apache.solr.BaseDistributedSearchTestCase.index_specific(BaseDistributedSearchTestCase.java:488) at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:139) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:875) at sun.reflect.GeneratedMethodAccessor47.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:150) at java.net.SocketInputStream.read(SocketInputStream.java:121) at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) at sun.security.ssl.InputRecord.read(InputRecord.java:503) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:954) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:911) at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) 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:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:465) ... 47 more REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch Error Message: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 at __randomizedtesting.SeedInfo.seed([2D26F091FD6BBC5A:ACC07E898A34DC66]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:569) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.GeneratedMethodAccessor47.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11363 lines...] [junit4] Suite: org.apache.solr.TestDistributedGrouping [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/init-core-data-001 [junit4] 2> 2348484 T6725 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 2348484 T6725 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 2348488 T6725 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 2348490 T6725 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2348498 T6725 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2348506 T6725 oejs.AbstractConnector.doStart Started [email protected]:51133 [junit4] 2> 2348510 T6725 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2348510 T6725 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2348511 T6725 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr [junit4] 2> 2348512 T6725 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/' [junit4] 2> 2348556 T6725 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/solr.xml [junit4] 2> 2348573 T6725 oasc.CoreContainer.<init> New CoreContainer 1845682023 [junit4] 2> 2348574 T6725 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/] [junit4] 2> 2348574 T6725 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2348574 T6725 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https [junit4] 2> 2348575 T6725 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2348575 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2348575 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 2348575 T6725 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2348576 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2348576 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2348576 T6725 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2348576 T6725 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2348578 T6725 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2348580 T6725 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2348580 T6725 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2348580 T6725 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2348587 T6735 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 2348589 T6735 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 2348589 T6735 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 2348590 T6735 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 2348631 T6735 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2348653 T6735 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2348656 T6735 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 2348662 T6735 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 2348775 T6735 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2348784 T6735 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 2348785 T6735 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2348795 T6735 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2348797 T6735 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2348800 T6735 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2348800 T6735 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 2348800 T6735 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 2348801 T6735 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2348801 T6735 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 2348801 T6735 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 2348802 T6735 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/ [junit4] 2> 2348802 T6735 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2348802 T6735 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/control/data/ [junit4] 2> 2348802 T6735 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e533e91 [junit4] 2> 2348803 T6735 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/control/data [junit4] 2> 2348803 T6735 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/control/data/index/ [junit4] 2> 2348803 T6735 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 2348804 T6735 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/control/data/index [junit4] 2> 2348804 T6735 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1214625384, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2348804 T6735 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-609e99e8))),segFN=segments_1,generation=1} [junit4] 2> 2348804 T6735 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2348805 T6735 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 2348808 T6735 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 2348809 T6735 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2348809 T6735 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 2348809 T6735 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 2348810 T6735 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2348810 T6735 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2348810 T6735 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 2348810 T6735 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 2348810 T6735 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 2348811 T6735 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2348811 T6735 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2348811 T6735 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2348811 T6735 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2348812 T6735 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2348812 T6735 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2348812 T6735 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2348812 T6735 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2348813 T6735 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2348813 T6735 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2348813 T6735 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 2348814 T6735 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 2348814 T6735 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2348814 T6735 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 2348815 T6735 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 2348815 T6735 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 2348815 T6735 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 2348815 T6735 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 2348815 T6735 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2348816 T6735 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348816 T6735 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348816 T6735 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348817 T6735 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348817 T6735 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348818 T6735 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348818 T6735 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348819 T6735 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2348819 T6735 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 2348820 T6735 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 2348826 T6735 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2348827 T6735 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2348828 T6735 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2348830 T6735 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2348830 T6735 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 2348833 T6735 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2348834 T6735 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2348834 T6735 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2348834 T6735 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-609e99e8))),segFN=segments_1,generation=1} [junit4] 2> 2348835 T6735 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2348835 T6735 oass.SolrIndexSearcher.<init> Opening Searcher@165e3b7a[collection1] main [junit4] 2> 2348835 T6735 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 2348835 T6735 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 2348836 T6735 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 2348836 T6735 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 2348836 T6735 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2348836 T6735 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 2348844 T6735 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2348886 T6736 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@165e3b7a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2348893 T6735 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2348894 T6725 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2348895 T6725 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 2348897 T6725 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 2348902 T6725 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 2348903 T6725 oejs.AbstractConnector.doStart Started [email protected]:51134 [junit4] 2> 2348906 T6725 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 2348906 T6725 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2348907 T6725 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr [junit4] 2> 2348907 T6725 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/' [junit4] 2> 2348928 T6725 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/solr.xml [junit4] 2> 2348938 T6725 oasc.CoreContainer.<init> New CoreContainer 1589426748 [junit4] 2> 2348938 T6725 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/] [junit4] 2> 2348939 T6725 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 2348939 T6725 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https [junit4] 2> 2348939 T6725 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 2348939 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 2348940 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 2348940 T6725 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 2348940 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 2348940 T6725 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 2348941 T6725 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 2348941 T6725 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 2348944 T6725 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 2348945 T6725 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2348945 T6725 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2348946 T6725 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 2348949 T6747 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 2348951 T6747 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 2348952 T6747 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 2348952 T6747 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 2349011 T6747 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2349037 T6747 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 2349038 T6747 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 2349043 T6747 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 2349151 T6747 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2349155 T6747 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 2349156 T6747 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 2349162 T6747 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2349164 T6747 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 2349166 T6747 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2349166 T6747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 2349166 T6747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 2349167 T6747 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2349167 T6747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 2349167 T6747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 2349167 T6747 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/ [junit4] 2> 2349168 T6747 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2349168 T6747 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/shard0/data/ [junit4] 2> 2349168 T6747 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e533e91 [junit4] 2> 2349169 T6747 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/shard0/data [junit4] 2> 2349169 T6747 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/shard0/data/index/ [junit4] 2> 2349169 T6747 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/shard0/data/index' doesn't exist. Creating new index... [junit4] 2> 2349170 T6747 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-2D26F091FD6BBC5A-001/tempDir-001/shard0/data/index [junit4] 2> 2349171 T6747 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1214625384, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2349171 T6747 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-b39b7b5))),segFN=segments_1,generation=1} [junit4] 2> 2349171 T6747 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2349172 T6747 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 2349175 T6747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 2349175 T6747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2349175 T6747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 2349176 T6747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 2349176 T6747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2349176 T6747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2349176 T6747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 2349177 T6747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 2349177 T6747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 2349177 T6747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2349177 T6747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 2349178 T6747 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 2349178 T6747 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2349178 T6747 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2349179 T6747 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 2349179 T6747 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 2349179 T6747 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 2349180 T6747 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 2349181 T6747 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 2349181 T6747 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 2349181 T6747 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 2349182 T6747 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 2349182 T6747 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 2349182 T6747 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 2349183 T6747 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 2349183 T6747 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 2349183 T6747 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 2349183 T6747 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 2349184 T6747 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349184 T6747 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349185 T6747 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349185 T6747 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349186 T6747 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349186 T6747 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349186 T6747 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349187 T6747 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 2349187 T6747 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 2349188 T6747 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 2349189 T6747 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2349191 T6747 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2349192 T6747 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2349193 T6747 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 2349194 T6747 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 2349197 T6747 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 2349197 T6747 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 2349199 T6747 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2349200 T6747 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-b39b7b5))),segFN=segments_1,generation=1} [junit4] 2> 2349200 T6747 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 2349201 T6747 oass.SolrIndexSearcher.<init> Opening Searcher@57045e75[collection1] main [junit4] 2> 2349201 T6747 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 2349201 T6747 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 2349202 T6747 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 2349202 T6747 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 2349202 T6747 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 2349202 T6747 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 2349213 T6747 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2349251 T6747 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 2349252 T6748 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57045e75[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2349253 T6725 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 2349254 T6725 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> ASYNC NEW_CORE C948 name=collection1 org.apache.solr.core.SolrCore@7748b505 [junit4] 2> 2349305 T6733 C948 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1483493896953528320)} 0 2 [junit4] 2> ASYNC NEW_CORE C949 name=collection1 org.apache.solr.core.SolrCore@1fa5e3c [junit4] 2> 2349373 T6744 C949 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1483493897015394304)} 0 11 [junit4] 2> 2349379 T6734 C948 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2349380 T6734 C948 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 2349380 T6734 C948 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 2349380 T6734 C948 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 2349381 T6734 C948 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 2 [junit4] 2> 2349386 T6745 C949 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2349386 T6745 C949 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit. [junit4] 2> 2349386 T6745 C949 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 2349387 T6745 C949 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 2349387 T6745 C949 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 1 [junit4] 2> 2349413 T6729 C948 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=20 [junit4] 2> ASYNC NEW_CORE C950 name=collection1 org.apache.solr.core.SolrCore@1fa5e3c [junit4] 2> 2349487 T6744 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.distributed.first=true&distrib=false&fl=id&group.limit=10&start=0&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:51134/|[ff01::083]:33332/&rows=100&version=2&q=*:*&NOW=1414770028183&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=8 [junit4] 2> 2349488 T6746 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=https://127.0.0.1:51134/|[ff01::083]:33332/&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} status=0 QTime=72 [junit4] 2> ASYNC NEW_CORE C951 name=collection1 org.apache.solr.core.SolrCore@7748b505 [junit4] 2> 2349495 T6730 C951 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&hl=true&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=1 [junit4] 2> 2349503 T6741 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=10&start=0&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:51134/|[ff01::213]:33332/&rows=100&version=2&q=*:*&NOW=1414770028265&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=1 [junit4] 2> 2349504 T6745 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=https://127.0.0.1:51134/|[ff01::213]:33332/&hl=true&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} status=0 QTime=6 [junit4] 2> 2349507 T6731 C951 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=0 [junit4] 2> 2349514 T6743 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={facet.field=a_t&group.distributed.first=true&distrib=false&fl=id&group.limit=10&start=0&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:51134/|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&rows=100&version=2&q=*:*&NOW=1414770028277&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 2349519 T6744 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={facet.field=a_t&distrib=false&fl=id&group.limit=10&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:51134/|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1414770028277&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=1 [junit4] 2> 2349521 T6742 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=https://127.0.0.1:51134/|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&facet.field=a_t&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true} status=0 QTime=11 [junit4] 2> 2349525 T6733 C951 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&stats=true&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1} hits=0 status=0 QTime=1 [junit4] 2> 2349534 T6741 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.distributed.first=true&distrib=false&fl=id&group.limit=10&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/|https://127.0.0.1:51134/|[ff01::083]:33332/&rows=100&version=2&q=*:*&stats=false&NOW=1414770028297&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 2349538 T6745 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&group.limit=10&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/|https://127.0.0.1:51134/|[ff01::083]:33332/&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1414770028297&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=1 [junit4] 2> 2349542 T6746 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|https://127.0.0.1:51134/|[ff01::083]:33332/&stats=true&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=12 [junit4] 2> 2349610 T6732 C951 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&wt=javabin&group.field=a_i1&group=true} hits=0 status=0 QTime=62 [junit4] 2> 2349634 T6744 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.distributed.first=true&distrib=false&fl=id&group.limit=10&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:51134/&rows=100&version=2&spellcheck.build=true&spellcheck.extendedResults=false&q=kings&spellcheck=true&NOW=1414770028380&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin&group.field=a_i1&group=true} status=0 QTime=1 [junit4] 2> 2349635 T6743 C950 oashc.SpellCheckComponent.finishStage [ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:51134/ null [junit4] 2> 2349635 T6743 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={qt=spellCheckCompRH&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:51134/&spellcheck=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=22 [junit4] 2> 2349662 T6734 C951 oasc.SolrCore.execute [collection1] webapp= path=/select params={facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=10&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} hits=0 status=0 QTime=22 [junit4] 2> 2349669 T6741 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={facet.field=a_t&group.distributed.first=true&distrib=false&fl=id&group.limit=10&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:51134/&rows=100&version=2&q=*:*&group.truncate=true&NOW=1414770028433&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 2349674 T6745 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={facet.field=a_t&distrib=false&fl=id&group.limit=10&start=0&f.a_t.facet.mincount=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:51134/&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1414770028433&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=1 [junit4] 2> 2349675 T6742 C950 oasc.SolrCore.execute [collection1] webapp= path=/select params={facet.field=a_t&fl=id,a_i1&group.limit=10&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&shards=https://127.0.0.1:51134/&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=9 [junit4] 2> 2349684 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1483493897349890048)]} 0 4 [junit4] 2> 2349688 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1483493897356181504)]} 0 1 [junit4] 2> 2349692 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1483493897361424384)]} 0 0 [junit4] 2> 2349697 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1483493897365618688)]} 0 1 [junit4] 2> 2349702 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1483493897370861568)]} 0 1 [junit4] 2> 2349705 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1483493897375055872)]} 0 0 [junit4] 2> 2349708 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1483493897378201600)]} 0 0 [junit4] 2> 2349712 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1483493897382395904)]} 0 1 [junit4] 2> 2349715 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1483493897385541632)]} 0 0 [junit4] 2> 2349719 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1483493897388687360)]} 0 1 [junit4] 2> 2349722 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1483493897392881664)]} 0 0 [junit4] 2> 2349727 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1483493897397075968)]} 0 1 [junit4] 2> 2349731 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1483493897402318848)]} 0 0 [junit4] 2> 2349735 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1483493897406513152)]} 0 0 [junit4] 2> 2349739 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1483493897410707456)]} 0 0 [junit4] 2> 2349743 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1483493897414901760)]} 0 0 [junit4] 2> 2349747 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1483493897419096064)]} 0 0 [junit4] 2> 2349753 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1483493897425387520)]} 0 0 [junit4] 2> 2349759 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1483493897430630400)]} 0 1 [junit4] 2> 2349762 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1483493897434824704)]} 0 0 [junit4] 2> 2349768 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1483493897440067584)]} 0 1 [junit4] 2> 2349772 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1483493897445310464)]} 0 0 [junit4] 2> 2349776 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1483493897448456192)]} 0 1 [junit4] 2> 2349781 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1483493897452650496)]} 0 2 [junit4] 2> 2349785 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1483493897456844800)]} 0 2 [junit4] 2> 2349790 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1483493897463136256)]} 0 1 [junit4] 2> 2349797 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1483493897471524864)]} 0 0 [junit4] 2> 2349800 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1483493897474670592)]} 0 0 [junit4] 2> 2349804 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1483493897478864896)]} 0 0 [junit4] 2> 2349808 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1483493897483059200)]} 0 0 [junit4] 2> 2349814 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1483493897489350656)]} 0 3 [junit4] 2> 2349818 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1483493897493544960)]} 0 0 [junit4] 2> 2349823 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18 (1483493897497739264)]} 0 1 [junit4] 2> 2349828 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18 (1483493897504030720)]} 0 0 [junit4] 2> 2349832 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19 (1483493897508225024)]} 0 0 [junit4] 2> 2349835 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19 (1483493897511370752)]} 0 0 [junit4] 2> 2349840 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20 (1483493897516613632)]} 0 0 [junit4] 2> 2349844 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20 (1483493897519759360)]} 0 1 [junit4] 2> 2349848 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21 (1483493897525002240)]} 0 0 [junit4] 2> 2349852 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21 (1483493897528147968)]} 0 1 [junit4] 2> 2349858 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22 (1483493897533390848)]} 0 2 [junit4] 2> 2349861 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22 (1483493897538633728)]} 0 0 [junit4] 2> 2349864 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[23 (1483493897541779456)]} 0 0 [junit4] 2> 2349868 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[23 (1483493897545973760)]} 0 0 [junit4] 2> 2349872 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1483493897550168064)]} 0 1 [junit4] 2> 2349875 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1483493897553313792)]} 0 0 [junit4] 2> 2349878 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1483493897556459520)]} 0 0 [junit4] 2> 2349882 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1483493897560653824)]} 0 0 [junit4] 2> 2349885 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1483493897563799552)]} 0 0 [junit4] 2> 2349889 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1483493897567993856)]} 0 0 [junit4] 2> 2349894 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1483493897572188160)]} 0 1 [junit4] 2> 2349897 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1483493897576382464)]} 0 0 [junit4] 2> 2349903 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1483493897582673920)]} 0 0 [junit4] 2> 2349906 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1483493897585819648)]} 0 0 [junit4] 2> 2349909 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1483493897588965376)]} 0 1 [junit4] 2> 2349912 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1483493897591062528)]} 0 1 [junit4] 2> 2349916 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1483493897595256832)]} 0 1 [junit4] 2> 2349919 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1483493897599451136)]} 0 0 [junit4] 2> 2349922 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1483493897602596864)]} 0 0 [junit4] 2> 2349925 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1483493897605742592)]} 0 0 [junit4] 2> 2349928 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1483493897608888320)]} 0 0 [junit4] 2> 2349931 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1483493897612034048)]} 0 0 [junit4] 2> 2349935 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1483493897615179776)]} 0 1 [junit4] 2> 2349939 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1483493897620422656)]} 0 0 [junit4] 2> 2349944 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1483493897624616960)]} 0 1 [junit4] 2> 2349948 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1483493897628811264)]} 0 1 [junit4] 2> 2349953 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1483493897634054144)]} 0 1 [junit4] 2> 2349957 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1483493897639297024)]} 0 0 [junit4] 2> 2349962 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1483493897644539904)]} 0 0 [junit4] 2> 2349965 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1483493897647685632)]} 0 0 [junit4] 2> 2349970 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1483493897651879936)]} 0 1 [junit4] 2> 2349973 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1483493897656074240)]} 0 0 [junit4] 2> 2349978 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1483493897661317120)]} 0 0 [junit4] 2> 2349982 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1483493897665511424)]} 0 0 [junit4] 2> 2349987 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1483493897670754304)]} 0 0 [junit4] 2> 2349991 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1483493897674948608)]} 0 0 [junit4] 2> 2349996 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1483493897679142912)]} 0 1 [junit4] 2> 2349999 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1483493897683337216)]} 0 0 [junit4] 2> 2350003 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1483493897687531520)]} 0 0 [junit4] 2> 2350010 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1483493897693822976)]} 0 1 [junit4] 2> 2350013 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1483493897698017280)]} 0 0 [junit4] 2> 2350016 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1483493897701163008)]} 0 0 [junit4] 2> 2350019 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1483493897704308736)]} 0 0 [junit4] 2> 2350022 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1483493897707454464)]} 0 0 [junit4] 2> 2350027 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1483493897712697344)]} 0 1 [junit4] 2> 2350030 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1483493897714794496)]} 0 1 [junit4] 2> 2350033 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1483493897718988800)]} 0 0 [junit4] 2> 2350036 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1483493897722134528)]} 0 0 [junit4] 2> 2350041 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1483493897727377408)]} 0 0 [junit4] 2> 2350044 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1483493897730523136)]} 0 0 [junit4] 2> 2350047 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1483493897733668864)]} 0 0 [junit4] 2> 2350051 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1483493897736814592)]} 0 1 [junit4] 2> 2350054 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1483493897739960320)]} 0 1 [junit4] 2> 2350056 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1483493897743106048)]} 0 0 [junit4] 2> 2350059 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1483493897746251776)]} 0 0 [junit4] 2> 2350062 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1483493897749397504)]} 0 0 [junit4] 2> 2350065 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1483493897752543232)]} 0 0 [junit4] 2> 2350070 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1483493897757786112)]} 0 0 [junit4] 2> 2350073 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1483493897760931840)]} 0 0 [junit4] 2> 2350076 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1483493897764077568)]} 0 0 [junit4] 2> 2350080 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1483493897768271872)]} 0 0 [junit4] 2> 2350086 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1483493897773514752)]} 0 1 [junit4] 2> 2350091 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1483493897778757632)]} 0 1 [junit4] 2> 2350098 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1483493897787146240)]} 0 1 [junit4] 2> 2350102 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1483493897791340544)]} 0 0 [junit4] 2> 2350105 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1483493897794486272)]} 0 0 [junit4] 2> 2350109 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1483493897798680576)]} 0 1 [junit4] 2> 2350112 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1483493897801826304)]} 0 0 [junit4] 2> 2350115 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1483493897804972032)]} 0 0 [junit4] 2> 2350118 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1483493897808117760)]} 0 0 [junit4] 2> 2350121 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1483493897811263488)]} 0 0 [junit4] 2> 2350126 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1483493897816506368)]} 0 0 [junit4] 2> 2350130 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1483493897819652096)]} 0 1 [junit4] 2> 2350133 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1483493897823846400)]} 0 0 [junit4] 2> 2350137 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1483493897828040704)]} 0 0 [junit4] 2> 2350140 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1483493897831186432)]} 0 0 [junit4] 2> 2350143 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1483493897834332160)]} 0 0 [junit4] 2> 2350147 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1483493897838526464)]} 0 1 [junit4] 2> 2350154 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1483493897845866496)]} 0 0 [junit4] 2> 2350157 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1483493897849012224)]} 0 0 [junit4] 2> 2350159 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1483493897851109376)]} 0 0 [junit4] 2> 2350163 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1483493897854255104)]} 0 1 [junit4] 2> 2350165 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1483493897857400832)]} 0 0 [junit4] 2> 2350168 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1483493897860546560)]} 0 0 [junit4] 2> 2350171 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1483493897863692288)]} 0 0 [junit4] 2> 2350178 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1483493897871032320)]} 0 0 [junit4] 2> 2350181 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1483493897874178048)]} 0 0 [junit4] 2> 2350184 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1483493897877323776)]} 0 0 [junit4] 2> 2350187 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1483493897880469504)]} 0 0 [junit4] 2> 2350192 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1483493897884663808)]} 0 1 [junit4] 2> 2350198 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1483493897892003840)]} 0 1 [junit4] 2> 2350201 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1483493897894100992)]} 0 1 [junit4] 2> 2350204 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1483493897897246720)]} 0 1 [junit4] 2> 2350207 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1483493897900392448)]} 0 1 [junit4] 2> 2350209 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1483493897903538176)]} 0 0 [junit4] 2> 2350212 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1483493897906683904)]} 0 0 [junit4] 2> 2350215 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1483493897909829632)]} 0 0 [junit4] 2> 2350223 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1483493897918218240)]} 0 0 [junit4] 2> 2350226 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1483493897921363968)]} 0 0 [junit4] 2> 2350229 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1483493897924509696)]} 0 0 [junit4] 2> 2350231 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1483493897926606848)]} 0 0 [junit4] 2> 2350237 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1483493897931849728)]} 0 1 [junit4] 2> 2350240 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1483493897936044032)]} 0 0 [junit4] 2> 2350243 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1483493897939189760)]} 0 0 [junit4] 2> 2350246 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[500 (1483493897942335488)]} 0 0 [junit4] 2> 2350249 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[500 (1483493897945481216)]} 0 0 [junit4] 2> 2350253 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[501 (1483493897948626944)]} 0 1 [junit4] 2> 2350261 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[501 (1483493897958064128)]} 0 0 [junit4] 2> 2350264 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[502 (1483493897961209856)]} 0 0 [junit4] 2> 2350267 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[502 (1483493897964355584)]} 0 0 [junit4] 2> 2350270 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[503 (1483493897967501312)]} 0 0 [junit4] 2> 2350273 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[503 (1483493897970647040)]} 0 0 [junit4] 2> 2350276 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[504 (1483493897973792768)]} 0 0 [junit4] 2> 2350281 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[504 (1483493897979035648)]} 0 0 [junit4] 2> 2350285 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[505 (1483493897983229952)]} 0 1 [junit4] 2> 2350288 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[505 (1483493897985327104)]} 0 1 [junit4] 2> 2350291 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[506 (1483493897989521408)]} 0 0 [junit4] 2> 2350294 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[506 (1483493897992667136)]} 0 0 [junit4] 2> 2350297 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[507 (1483493897995812864)]} 0 0 [junit4] 2> 2350304 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[507 (1483493898003152896)]} 0 0 [junit4] 2> 2350309 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[508 (1483493898008395776)]} 0 0 [junit4] 2> 2350312 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[508 (1483493898011541504)]} 0 0 [junit4] 2> 2350314 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[509 (1483493898013638656)]} 0 0 [junit4] 2> 2350317 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[509 (1483493898016784384)]} 0 0 [junit4] 2> 2350323 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[510 (1483493898022027264)]} 0 1 [junit4] 2> 2350328 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[510 (1483493898028318720)]} 0 0 [junit4] 2> 2350331 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[511 (1483493898031464448)]} 0 0 [junit4] 2> 2350334 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[511 (1483493898034610176)]} 0 0 [junit4] 2> 2350337 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[512 (1483493898037755904)]} 0 0 [junit4] 2> 2350340 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[512 (1483493898040901632)]} 0 0 [junit4] 2> 2350343 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[513 (1483493898044047360)]} 0 1 [junit4] 2> 2350349 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[513 (1483493898049290240)]} 0 1 [junit4] 2> 2350355 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[514 (1483493898055581696)]} 0 1 [junit4] 2> 2350357 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[514 (1483493898058727424)]} 0 0 [junit4] 2> 2350360 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[515 (1483493898061873152)]} 0 0 [junit4] 2> 2350363 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[515 (1483493898065018880)]} 0 0 [junit4] 2> 2350368 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[516 (1483493898070261760)]} 0 0 [junit4] 2> 2350370 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[516 (1483493898072358912)]} 0 0 [junit4] 2> 2350373 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[517 (1483493898075504640)]} 0 0 [junit4] 2> 2350377 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[517 (1483493898078650368)]} 0 1 [junit4] 2> 2350380 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[518 (1483493898082844672)]} 0 0 [junit4] 2> 2350385 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[518 (1483493898088087552)]} 0 0 [junit4] 2> 2350388 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[519 (1483493898091233280)]} 0 0 [junit4] 2> 2350391 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[519 (1483493898094379008)]} 0 1 [junit4] 2> 2350393 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[520 (1483493898096476160)]} 0 0 [junit4] 2> 2350396 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[520 (1483493898099621888)]} 0 0 [junit4] 2> 2350399 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[521 (1483493898102767616)]} 0 0 [junit4] 2> 2350405 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[521 (1483493898108010496)]} 0 1 [junit4] 2> 2350414 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[522 (1483493898118496256)]} 0 0 [junit4] 2> 2350417 T6743 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[522 (1483493898121641984)]} 0 0 [junit4] 2> 2350420 T6730 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[523 (1483493898124787712)]} 0 0 [junit4] 2> 2350423 T6744 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[523 (1483493898127933440)]} 0 0 [junit4] 2> 2350426 T6731 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[524 (1483493898131079168)]} 0 0 [junit4] 2> 2350429 T6741 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[524 (1483493898134224896)]} 0 0 [junit4] 2> 2350432 T6732 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[525 (1483493898136322048)]} 0 1 [junit4] 2> 2350435 T6745 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[525 (1483493898140516352)]} 0 0 [junit4] 2> 2350438 T6733 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[526 (1483493898143662080)]} 0 0 [junit4] 2> 2350444 T6742 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[526 (1483493898148904960)]} 0 1 [junit4] 2> 2350449 T6734 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[527 (1483493898155196416)]} 0 0 [junit4] 2> 2350452 T6746 C950 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[527 (1483493898158342144)]} 0 0 [junit4] 2> 2350456 T6729 C951 oasup.LogUpdateProcessor.finish [collection1] webapp= [...truncated too long message...] 2> 4106919 T9308 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:51858 51858 [junit4] 2> 4106922 T9597 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:51858 51858 [junit4] 2> 4106924 T9597 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 5 /solr/collections/awholynewstresscollection_collection1_0/state.json [junit4] 2> 5 /solr/collections/awholynewstresscollection_collection0_0/state.json [junit4] 2> 4 /solr/aliases.json [junit4] 2> 4 /solr/clusterstate.json [junit4] 2> 2 /solr/collections/awholynewcollection_2/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 4 /solr/live_nodes [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=2D26F091FD6BBC5A -Dtests.slow=true -Dtests.locale=zh_TW -Dtests.timezone=Europe/Prague -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 751s | CollectionsAPIDistributedZkTest.testDistribSearch <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 [junit4] > at __randomizedtesting.SeedInfo.seed([2D26F091FD6BBC5A:ACC07E898A34DC66]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:569) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 4106951 T9308 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-2D26F091FD6BBC5A-001 [junit4] 2> 751441 T9307 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=zh_TW, timezone=Europe/Prague [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_20 (64-bit)/cpus=2,threads=1,free=190399520,total=411566080 [junit4] 2> NOTE: All tests run in this JVM: [TestFieldSortValues, LoggingHandlerTest, ParsingFieldUpdateProcessorsTest, FileUtilsTest, URLClassifyProcessorTest, TestStressUserVersions, FastVectorHighlighterTest, CollectionsAPIAsyncDistributedZkTest, TestCloudPivotFacet, TestSolrCoreProperties, TestDistribDocBasedVersion, ExternalCollectionsTest, TestSolrQueryParser, JSONWriterTest, FieldMutatingUpdateProcessorTest, TestCloudInspectUtil, BasicDistributedZkTest, TestFieldResource, XmlUpdateRequestHandlerTest, TestLMDirichletSimilarityFactory, TestRecovery, TestJmxMonitoredMap, UnloadDistributedZkTest, RollingRestartTest, TestSchemaManager, TestFieldTypeCollectionResource, SharedFSAutoReplicaFailoverTest, HdfsChaosMonkeySafeLeaderTest, ZkCLITest, TestSolrXmlPersistor, ModifyConfFileTest, TestRealTimeGet, TestFieldCollectionResource, TestJmxIntegration, FullSolrCloudDistribCmdsTest, TestBinaryField, TestComplexPhraseQParserPlugin, TestLuceneMatchVersion, TestBadConfig, TestNonDefinedSimilarityFactory, TestPHPSerializedResponseWriter, CloudExitableDirectoryReaderTest, TestCopyFieldCollectionResource, TestCollationField, TestHighFrequencyDictionaryFactory, MultiThreadedOCPTest, BasicDistributedZk2Test, CSVRequestHandlerTest, DocValuesMultiTest, DistributedTermsComponentTest, AssignTest, LukeRequestHandlerTest, LeaderElectionIntegrationTest, TestRawResponseWriter, TestCursorMarkWithoutUniqueKey, TestReRankQParserPlugin, SliceStateTest, HdfsBasicDistributedZkTest, TestReloadAndDeleteDocs, MinimalSchemaTest, TestLeaderElectionZkExpiry, TestMaxScoreQueryParser, TestIBSimilarityFactory, TestDefaultSearchFieldResource, TestDocSet, DocValuesTest, DisMaxRequestHandlerTest, TestFastOutputStream, UUIDFieldTest, TestManagedStopFilterFactory, DistributedIntervalFacetingTest, DateRangeFieldTest, PluginInfoTest, DistributedSuggestComponentTest, SimpleFacetsTest, TestSimpleQParserPlugin, TestSearchPerf, TestFastWriter, TermVectorComponentTest, AnalysisErrorHandlingTest, TestImplicitCoreProperties, TestManagedResource, TestHashPartitioner, PrimitiveFieldTypeTest, TestSolrDeletionPolicy1, PingRequestHandlerTest, SolrIndexConfigTest, LeaderElectionTest, ReplicationFactorTest, BinaryUpdateRequestHandlerTest, LeaderFailoverAfterPartitionTest, HardAutoCommitTest, CursorPagingTest, DirectSolrConnectionTest, TestSortingResponseWriter, MigrateRouteKeyTest, TestManagedSchema, TestCustomSort, DistributedSpellCheckComponentTest, QueryParsingTest, UpdateRequestProcessorFactoryTest, ConvertedLegacyTest, TestElisionMultitermQuery, BlockCacheTest, TestCloudManagedSchema, TestReversedWildcardFilterFactory, HighlighterConfigTest, TestComponentsName, TestSort, StatsComponentTest, DOMUtilTest, SharedFSAutoReplicaFailoverUtilsTest, TestFunctionQuery, TermsComponentTest, TestBulkSchemaConcurrent, ChaosMonkeyNothingIsSafeTest, ZkNodePropsTest, CurrencyFieldXmlFileTest, TestExceedMaxTermLength, DefaultValueUpdateProcessorTest, DocValuesMissingTest, ZkSolrClientTest, UniqFieldsUpdateProcessorFactoryTest, TriLevelCompositeIdRoutingTest, CoreAdminRequestStatusTest, TestPhraseSuggestions, ResponseLogComponentTest, TestReload, DistributedQueueTest, TestTrie, TestIndexSearcher, OverriddenZkACLAndCredentialsProvidersTest, TestSearcherReuse, RAMDirectoryFactoryTest, DirectSolrSpellCheckerTest, HdfsCollectionsAPIDistributedZkTest, HdfsWriteToMultipleCollectionsTest, TestAtomicUpdateErrorCases, FieldAnalysisRequestHandlerTest, TestFuzzyAnalyzedSuggestions, DeleteShardTest, TestSerializedLuceneMatchVersion, NoCacheHeaderTest, TestDynamicFieldCollectionResource, TestManagedSynonymFilterFactory, TestRTGBase, HdfsLockFactoryTest, RemoteQueryErrorTest, DistributedQueryComponentOptimizationTest, TestSchemaVersionResource, OutOfBoxZkACLAndCredentialsProvidersTest, MoreLikeThisHandlerTest, DocExpirationUpdateProcessorFactoryTest, TestCodecSupport, TestOmitPositions, TestRequestStatusCollectionAPI, AnalyticsMergeStrategyTest, SimpleCollectionCreateDeleteTest, TestDistributedGrouping, HighlighterTest, TestReplicaProperties, SchemaVersionSpecificBehaviorTest, CacheHeaderTest, TestRandomMergePolicy, DistributedFacetPivotSmallTest, ClusterStateUpdateTest, TestSchemaNameResource, DocumentAnalysisRequestHandlerTest, TestMiniSolrCloudCluster, PolyFieldTest, TestSolrQueryParserDefaultOperatorResource, DirectUpdateHandlerOptimizeTest, TestSolr4Spatial2, TestStressLucene, TestCSVLoader, TestIndexingPerformance, TestFieldTypeResource, ReturnFieldsTest, CurrencyFieldOpenExchangeTest, TestBinaryResponseWriter, CoreMergeIndexesAdminHandlerTest, HdfsDirectoryTest, ZkControllerTest, BadComponentTest, RequestHandlersTest, TestFileDictionaryLookup, SynonymTokenizerTest, OpenCloseCoreStressTest, RecoveryZkTest, TestManagedSchemaFieldTypeResource, DirectUpdateHandlerTest, TestStressRecovery, TestPseudoReturnFields, AliasIntegrationTest, LegacyHTMLStripCharFilterTest, HighlighterMaxOffsetTest, TestCollapseQParserPlugin, CopyFieldTest, XsltUpdateRequestHandlerTest, OverseerTest, WordBreakSolrSpellCheckerTest, TestModifyConfFiles, SolrInfoMBeanTest, TestMergePolicyConfig, TestFastLRUCache, TestPartialUpdateDeduplication, EnumFieldTest, SystemInfoHandlerTest, ShardRoutingTest, SolrIndexSplitterTest, TestZkChroot, SignatureUpdateProcessorFactoryTest, LeaderInitiatedRecoveryOnCommitTest, TestNoOpRegenerator, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest] [junit4] Completed in 751.97s, 1 test, 1 error <<< FAILURES! [...truncated 694 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:525: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:473: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:502: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1358: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:965: There were test failures: 441 suites, 1801 tests, 2 errors, 49 ignored (20 assumptions) Total time: 164 minutes 16 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
