You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/07/19 20:41:24 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_65) - Build #
10851 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10851/
Java: 32bit/jdk1.7.0_65 -client -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.TestDistributedGrouping.testDistribSearch
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:40022
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:40022
at __randomizedtesting.SeedInfo.seed([B76560D5B83CA6BE:3683EECDCF63C682]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
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:487)
at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:140)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:442)
at sun.security.ssl.InputRecord.read(InputRecord.java:480)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:102)
at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java: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:449)
... 48 more
Build Log:
[...truncated 11083 lines...]
[junit4] Suite: org.apache.solr.TestDistributedGrouping
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/init-core-data-001
[junit4] 2> 308669 T721 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 308669 T721 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 308671 T721 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 308672 T721 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 308676 T721 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 308677 T721 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:36954
[junit4] 2> 308679 T721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 308679 T721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 308679 T721 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr
[junit4] 2> 308680 T721 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 308693 T721 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/solr.xml
[junit4] 2> 308735 T721 oasc.CoreContainer.<init> New CoreContainer 15177962
[junit4] 2> 308736 T721 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 308736 T721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 308737 T721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https
[junit4] 2> 308737 T721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 308737 T721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 308737 T721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 308738 T721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 308738 T721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 308738 T721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 308739 T721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 308741 T721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 308741 T721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 308741 T721 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 308744 T731 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 308745 T731 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 308745 T731 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 308746 T731 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 308804 T731 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 308861 T731 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 308862 T731 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 308872 T731 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 309190 T731 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 309196 T731 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 309198 T731 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 309203 T731 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 309206 T731 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 309208 T731 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 309208 T731 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 309208 T731 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 309209 T731 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 309209 T731 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 309209 T731 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 309209 T731 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 309209 T731 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 309210 T731 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/control/data/
[junit4] 2> 309210 T731 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a2358
[junit4] 2> 309210 T731 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/control/data
[junit4] 2> 309211 T731 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/control/data/index/
[junit4] 2> 309211 T731 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 309211 T731 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/control/data/index
[junit4] 2> 309211 T731 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=73.87109375, floorSegmentMB=1.6005859375, forceMergeDeletesPctAllowed=19.716760151842085, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 309212 T731 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1a160e2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ad7cb0)),segFN=segments_1,generation=1}
[junit4] 2> 309212 T731 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 309212 T731 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 309215 T731 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 309215 T731 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 309216 T731 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 309216 T731 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 309216 T731 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 309216 T731 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 309216 T731 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 309217 T731 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 309217 T731 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 309217 T731 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 309217 T731 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 309218 T731 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 309218 T731 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 309218 T731 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 309218 T731 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 309219 T731 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 309219 T731 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 309220 T731 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 309220 T731 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 309220 T731 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 309221 T731 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 309221 T731 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 309221 T731 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 309221 T731 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 309222 T731 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 309222 T731 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309222 T731 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309223 T731 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309223 T731 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309224 T731 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309224 T731 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309224 T731 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309225 T731 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309225 T731 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 309225 T731 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 309229 T731 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 309230 T731 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 309232 T731 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 309232 T731 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 309233 T731 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=17.9521484375, floorSegmentMB=0.775390625, forceMergeDeletesPctAllowed=9.146736458017905, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5272303526174732
[junit4] 2> 309233 T731 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1a160e2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ad7cb0)),segFN=segments_1,generation=1}
[junit4] 2> 309234 T731 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 309234 T731 oass.SolrIndexSearcher.<init> Opening Searcher@19e195a[collection1] main
[junit4] 2> 309234 T731 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 309234 T731 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 309235 T731 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 309235 T731 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 309235 T731 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 309235 T731 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 309240 T731 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 309269 T732 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19e195a[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 309279 T731 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 309280 T721 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 309281 T721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 309286 T721 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 309299 T721 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 309302 T721 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60586
[junit4] 2> 309305 T721 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 309305 T721 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 309306 T721 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr
[junit4] 2> 309307 T721 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 309325 T721 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/solr.xml
[junit4] 2> 309356 T721 oasc.CoreContainer.<init> New CoreContainer 6407262
[junit4] 2> 309357 T721 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 309365 T721 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 309365 T721 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https
[junit4] 2> 309365 T721 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 309366 T721 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 309366 T721 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 309366 T721 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 309367 T721 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 309367 T721 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 309367 T721 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 309370 T721 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 309370 T721 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 309371 T721 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 309374 T743 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 309375 T743 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 309375 T743 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 309376 T743 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 309425 T743 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 309484 T743 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 309484 T743 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 309492 T743 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 309810 T743 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 309815 T743 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 309818 T743 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 309822 T743 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 309825 T743 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 309827 T743 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 309827 T743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 309828 T743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 309828 T743 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 309828 T743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 309828 T743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 309829 T743 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 309829 T743 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 309829 T743 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard0/data/
[junit4] 2> 309829 T743 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a2358
[junit4] 2> 309830 T743 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard0/data
[junit4] 2> 309830 T743 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard0/data/index/
[junit4] 2> 309830 T743 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard0/data/index' doesn't exist. Creating new index...
[junit4] 2> 309830 T743 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard0/data/index
[junit4] 2> 309831 T743 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=73.87109375, floorSegmentMB=1.6005859375, forceMergeDeletesPctAllowed=19.716760151842085, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 309831 T743 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@10c8cf1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9c1d80)),segFN=segments_1,generation=1}
[junit4] 2> 309831 T743 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 309832 T743 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 309836 T743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 309836 T743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 309836 T743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 309836 T743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 309837 T743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 309837 T743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 309837 T743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 309837 T743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 309837 T743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 309838 T743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 309838 T743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 309838 T743 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 309838 T743 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 309839 T743 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 309839 T743 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 309840 T743 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 309840 T743 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 309841 T743 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 309841 T743 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 309842 T743 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 309850 T743 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 309851 T743 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 309851 T743 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 309851 T743 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 309852 T743 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 309852 T743 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309853 T743 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309853 T743 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309854 T743 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309854 T743 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309855 T743 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309855 T743 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309856 T743 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 309857 T743 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 309858 T743 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 309863 T743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 309865 T743 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 309868 T743 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 309868 T743 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 309869 T743 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=17.9521484375, floorSegmentMB=0.775390625, forceMergeDeletesPctAllowed=9.146736458017905, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5272303526174732
[junit4] 2> 309870 T743 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@10c8cf1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9c1d80)),segFN=segments_1,generation=1}
[junit4] 2> 309870 T743 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 309870 T743 oass.SolrIndexSearcher.<init> Opening Searcher@1e5a09b[collection1] main
[junit4] 2> 309871 T743 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 309871 T743 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 309872 T743 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 309872 T743 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 309872 T743 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 309872 T743 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 309877 T743 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 309918 T744 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e5a09b[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 309931 T743 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 309931 T721 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 309932 T721 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C2855 name=collection1 org.apache.solr.core.SolrCore@b0737e
[junit4] 2> 310028 T728 C2855 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1474080380680142848)} 0 2
[junit4] 2> ASYNC NEW_CORE C2856 name=collection1 org.apache.solr.core.SolrCore@1565c5d
[junit4] 2> 310114 T741 C2856 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1474080380771368960)} 0 1
[junit4] 2> 310119 T729 C2855 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 310119 T729 C2855 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 310120 T729 C2855 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 310121 T729 C2855 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 310121 T729 C2855 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 2
[junit4] 2> 310125 T742 C2856 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 310125 T742 C2856 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 310126 T742 C2856 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 310127 T742 C2856 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 310127 T742 C2856 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 2
[junit4] 2> 310139 T730 C2855 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.limit=10&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=*:*&wt=javabin&distrib=false} hits=0 status=0 QTime=8
[junit4] 2> 310240 T740 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={fl=id&version=2&NOW=1405792599493&shard.url=https://127.0.0.1:60586/|[ff01::083]:33332/&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&distrib=false&group.limit=10&start=0&q=*:*&isShard=true&wt=javabin&group.distributed.first=true} status=0 QTime=17
[junit4] 2> 310252 T739 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.limit=10&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=*:*&wt=javabin&shards=https://127.0.0.1:60586/|[ff01::083]:33332/} status=0 QTime=106
[junit4] 2> 310255 T727 C2855 oasc.SolrCore.execute [collection1] webapp= path=/select params={hl.fl=a_t&group.limit=10&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=*:*&wt=javabin&hl=true&distrib=false} hits=0 status=0 QTime=0
[junit4] 2> 310265 T742 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={fl=id&version=2&NOW=1405792599605&shard.url=[ff01::213]:33332/|https://127.0.0.1:60586/&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&distrib=false&hl.fl=a_t&group.limit=10&start=0&isShard=true&q=*:*&wt=javabin&group.distributed.first=true&hl=false} status=0 QTime=0
[junit4] 2> 310266 T741 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={hl.fl=a_t&group.limit=10&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=*:*&wt=javabin&shards=[ff01::213]:33332/|https://127.0.0.1:60586/&hl=true} status=0 QTime=8
[junit4] 2> 310269 T728 C2855 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.limit=10&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=*:*&wt=javabin&distrib=false&facet.field=a_t&facet=true} hits=0 status=0 QTime=0
[junit4] 2> 310276 T739 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={fl=id&version=2&NOW=1405792599620&shard.url=https://127.0.0.1:60586/&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&facet.field=a_t&distrib=false&group.limit=10&start=0&isShard=true&q=*:*&wt=javabin&group.distributed.first=true&facet=false} status=0 QTime=1
[junit4] 2> 310292 T742 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={fl=id&version=2&NOW=1405792599620&shard.url=https://127.0.0.1:60586/&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&f.a_t.facet.limit=160&distrib=false&facet.field=a_t&group.limit=10&start=0&group.distributed.second=true&isShard=true&q=*:*&wt=javabin&facet=true} status=0 QTime=8
[junit4] 2> 310301 T740 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.limit=10&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=*:*&wt=javabin&shards=https://127.0.0.1:60586/&facet.field=a_t&facet=true} status=0 QTime=28
[junit4] 2> 310305 T730 C2855 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.limit=10&fl=id,a_i1&version=2&stats=true&sort=a_i1+asc,+id+asc&group=true&rows=100&stats.field=a_i1&group.field=a_i1&q=*:*&wt=javabin&distrib=false} hits=0 status=0 QTime=0
[junit4] 2> 310319 T739 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={fl=id&version=2&NOW=1405792599656&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:60586/&sort=a_i1+asc,+id+asc&group=true&rows=100&stats.field=a_i1&group.field=a_i1&distrib=false&group.limit=10&start=0&stats=false&isShard=true&q=*:*&wt=javabin&group.distributed.first=true} status=0 QTime=1
[junit4] 2> 310323 T742 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={fl=id&version=2&NOW=1405792599656&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:60586/&sort=a_i1+asc,+id+asc&group=true&rows=100&stats.field=a_i1&group.field=a_i1&distrib=false&group.limit=10&start=0&stats=true&group.distributed.second=true&isShard=true&q=*:*&wt=javabin} status=0 QTime=0
[junit4] 2> 310324 T741 C2856 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.limit=10&fl=id,a_i1&version=2&stats=true&sort=a_i1+asc,+id+asc&group=true&rows=100&stats.field=a_i1&group.field=a_i1&q=*:*&wt=javabin&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:60586/} status=0 QTime=15
[junit4] 2> 310348 T727 C2855 oasc.SolrCore.execute [collection1] webapp= path=/select params={spellcheck=true&group.limit=10&qt=spellCheckCompRH&spellcheck.build=true&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=kings&wt=javabin&distrib=false} hits=0 status=0 QTime=21
[junit4] 2> ASYNC NEW_CORE C2857 name=collection1 org.apache.solr.core.SolrCore@1565c5d
[junit4] 2> 310395 T739 C2857 oasc.SolrCore.execute [collection1] webapp= path=/select params={spellcheck=true&fl=id&version=2&NOW=1405792599716&shard.url=[ff01::213]:33332/|https://127.0.0.1:60586/|[ff01::114]:33332/|[ff01::083]:33332/&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&distrib=false&group.limit=10&start=0&spellcheck.count=5&spellcheck.build=true&spellcheck.extendedResults=false&spellcheck.onlyMorePopular=false&isShard=true&q=kings&wt=javabin&group.distributed.first=true} status=0 QTime=0
[junit4] 2> 310399 T740 C2857 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/|https://127.0.0.1:60586/|[ff01::114]:33332/|[ff01::083]:33332/ null
[junit4] 2> 310400 T740 C2857 oasc.SolrCore.execute [collection1] webapp= path=/select params={spellcheck=true&group.limit=10&qt=spellCheckCompRH&spellcheck.build=true&fl=id,a_i1&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=kings&wt=javabin&shards=[ff01::213]:33332/|https://127.0.0.1:60586/|[ff01::114]:33332/|[ff01::083]:33332/} status=0 QTime=31
[junit4] 2> ASYNC NEW_CORE C2858 name=collection1 org.apache.solr.core.SolrCore@b0737e
[junit4] 2> 310412 T729 C2858 oasc.SolrCore.execute [collection1] webapp= path=/select params={group.limit=10&group.truncate=true&fl=id,a_i1&fq=a_s:a&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&q=*:*&wt=javabin&distrib=false&facet.field=a_t&facet=true} hits=0 status=0 QTime=8
[junit4] 2> 310419 T741 C2857 oasc.SolrCore.execute [collection1] webapp= path=/select params={fq=a_s:a&fl=id&group.truncate=true&version=2&NOW=1405792599763&shard.url=https://127.0.0.1:60586/|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&distrib=false&facet.field=a_t&group.limit=10&start=0&isShard=true&q=*:*&wt=javabin&group.distributed.first=true&facet=false} status=0 QTime=0
[junit4] 2> 310423 T739 C2857 oasc.SolrCore.execute [collection1] webapp= path=/select params={fq=a_s:a&fl=id&group.truncate=true&version=2&NOW=1405792599763&shard.url=https://127.0.0.1:60586/|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&f.a_t.facet.limit=160&distrib=false&facet.field=a_t&group.limit=10&start=0&group.distributed.second=true&isShard=true&q=*:*&wt=javabin&facet=true} status=0 QTime=0
[junit4] 2> 310425 T742 C2857 oasc.SolrCore.execute [collection1] webapp= path=/select params={fq=a_s:a&fl=id,a_i1&group.truncate=true&version=2&sort=a_i1+asc,+id+asc&group=true&rows=100&group.field=a_i1&facet.field=a_t&group.limit=10&q=*:*&wt=javabin&shards=https://127.0.0.1:60586/|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&facet=true} status=0 QTime=9
[junit4] 2> 310431 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[1 (1474080381102718976)]} 0 3
[junit4] 2> 310437 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[1 (1474080381109010432)]} 0 2
[junit4] 2> 310442 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[2 (1474080381115301888)]} 0 1
[junit4] 2> 310447 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[2 (1474080381120544768)]} 0 1
[junit4] 2> 310451 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[3 (1474080381125787648)]} 0 1
[junit4] 2> 310455 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[3 (1474080381128933376)]} 0 1
[junit4] 2> 310459 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[4 (1474080381133127680)]} 0 1
[junit4] 2> 310463 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[4 (1474080381137321984)]} 0 1
[junit4] 2> 310467 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[5 (1474080381141516288)]} 0 1
[junit4] 2> 310471 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[5 (1474080381145710592)]} 0 1
[junit4] 2> 310475 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[6 (1474080381150953472)]} 0 1
[junit4] 2> 310479 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[6 (1474080381154099200)]} 0 1
[junit4] 2> 310483 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[7 (1474080381158293504)]} 0 1
[junit4] 2> 310487 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[7 (1474080381162487808)]} 0 1
[junit4] 2> 310492 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[8 (1474080381167730688)]} 0 1
[junit4] 2> 310496 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[8 (1474080381172973568)]} 0 0
[junit4] 2> 310501 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[9 (1474080381177167872)]} 0 1
[junit4] 2> 310506 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[9 (1474080381182410752)]} 0 1
[junit4] 2> 310510 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[10 (1474080381187653632)]} 0 0
[junit4] 2> 310515 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[10 (1474080381191847936)]} 0 1
[junit4] 2> 310519 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[11 (1474080381197090816)]} 0 1
[junit4] 2> 310524 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[11 (1474080381201285120)]} 0 1
[junit4] 2> 310529 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[12 (1474080381206528000)]} 0 1
[junit4] 2> 310533 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[12 (1474080381211770880)]} 0 0
[junit4] 2> 310538 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[14 (1474080381215965184)]} 0 1
[junit4] 2> 310542 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[14 (1474080381220159488)]} 0 1
[junit4] 2> 310545 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[15 (1474080381224353792)]} 0 0
[junit4] 2> 310548 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[15 (1474080381227499520)]} 0 0
[junit4] 2> 310551 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[16 (1474080381230645248)]} 0 0
[junit4] 2> 310555 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[16 (1474080381234839552)]} 0 0
[junit4] 2> 310560 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[17 (1474080381240082432)]} 0 2
[junit4] 2> 310565 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[17 (1474080381244276736)]} 0 2
[junit4] 2> 310569 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[18 (1474080381249519616)]} 0 0
[junit4] 2> 310573 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[18 (1474080381253713920)]} 0 0
[junit4] 2> 310578 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[19 (1474080381257908224)]} 0 1
[junit4] 2> 310582 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[19 (1474080381263151104)]} 0 0
[junit4] 2> 310587 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[20 (1474080381267345408)]} 0 1
[junit4] 2> 310591 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[20 (1474080381272588288)]} 0 0
[junit4] 2> 310596 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[21 (1474080381277831168)]} 0 0
[junit4] 2> 310601 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[21 (1474080381282025472)]} 0 1
[junit4] 2> 310605 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[22 (1474080381287268352)]} 0 0
[junit4] 2> 310610 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[22 (1474080381291462656)]} 0 1
[junit4] 2> 310614 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[23 (1474080381296705536)]} 0 0
[junit4] 2> 310618 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[23 (1474080381300899840)]} 0 0
[junit4] 2> 310622 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[100 (1474080381305094144)]} 0 1
[junit4] 2> 310625 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[100 (1474080381308239872)]} 0 0
[junit4] 2> 310629 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[101 (1474080381312434176)]} 0 0
[junit4] 2> 310633 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[101 (1474080381315579904)]} 0 1
[junit4] 2> 310637 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[102 (1474080381319774208)]} 0 1
[junit4] 2> 310640 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[102 (1474080381323968512)]} 0 0
[junit4] 2> 310644 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[103 (1474080381327114240)]} 0 1
[junit4] 2> 310647 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[103 (1474080381331308544)]} 0 0
[junit4] 2> 310651 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[104 (1474080381334454272)]} 0 1
[junit4] 2> 310654 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[104 (1474080381338648576)]} 0 0
[junit4] 2> 310658 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[105 (1474080381342842880)]} 0 0
[junit4] 2> 310661 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[105 (1474080381345988608)]} 0 0
[junit4] 2> 310665 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[106 (1474080381350182912)]} 0 0
[junit4] 2> 310669 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[106 (1474080381353328640)]} 0 1
[junit4] 2> 310673 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[107 (1474080381357522944)]} 0 1
[junit4] 2> 310676 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[107 (1474080381361717248)]} 0 0
[junit4] 2> 310680 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[108 (1474080381364862976)]} 0 1
[junit4] 2> 310684 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[108 (1474080381369057280)]} 0 1
[junit4] 2> 310688 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[109 (1474080381373251584)]} 0 1
[junit4] 2> 310692 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[109 (1474080381377445888)]} 0 1
[junit4] 2> 310696 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[110 (1474080381381640192)]} 0 1
[junit4] 2> 310700 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[110 (1474080381385834496)]} 0 1
[junit4] 2> 310704 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[111 (1474080381391077376)]} 0 0
[junit4] 2> 310709 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[111 (1474080381395271680)]} 0 1
[junit4] 2> 310713 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[112 (1474080381400514560)]} 0 0
[junit4] 2> 310717 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[112 (1474080381404708864)]} 0 0
[junit4] 2> 310722 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[113 (1474080381408903168)]} 0 1
[junit4] 2> 310725 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[113 (1474080381413097472)]} 0 0
[junit4] 2> 310729 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[114 (1474080381417291776)]} 0 0
[junit4] 2> 310733 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[114 (1474080381420437504)]} 0 1
[junit4] 2> 310737 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[115 (1474080381424631808)]} 0 1
[junit4] 2> 310740 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[115 (1474080381428826112)]} 0 0
[junit4] 2> 310742 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[116 (1474080381430923264)]} 0 0
[junit4] 2> 310745 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[116 (1474080381434068992)]} 0 0
[junit4] 2> 310748 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[117 (1474080381437214720)]} 0 0
[junit4] 2> 310752 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[117 (1474080381441409024)]} 0 0
[junit4] 2> 310756 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[118 (1474080381445603328)]} 0 0
[junit4] 2> 310758 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[118 (1474080381447700480)]} 0 0
[junit4] 2> 310762 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[119 (1474080381451894784)]} 0 0
[junit4] 2> 310766 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[119 (1474080381456089088)]} 0 0
[junit4] 2> 310771 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[120 (1474080381460283392)]} 0 1
[junit4] 2> 310775 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[120 (1474080381465526272)]} 0 1
[junit4] 2> 310779 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[121 (1474080381469720576)]} 0 0
[junit4] 2> 310783 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[121 (1474080381473914880)]} 0 0
[junit4] 2> 310787 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[122 (1474080381478109184)]} 0 0
[junit4] 2> 310792 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[122 (1474080381482303488)]} 0 1
[junit4] 2> 310795 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[123 (1474080381486497792)]} 0 0
[junit4] 2> 310799 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[123 (1474080381490692096)]} 0 0
[junit4] 2> 310803 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[124 (1474080381494886400)]} 0 0
[junit4] 2> 310807 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[124 (1474080381498032128)]} 0 1
[junit4] 2> 310811 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[125 (1474080381502226432)]} 0 1
[junit4] 2> 310814 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[125 (1474080381506420736)]} 0 0
[junit4] 2> 310818 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[126 (1474080381510615040)]} 0 1
[junit4] 2> 310822 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[126 (1474080381514809344)]} 0 1
[junit4] 2> 310826 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[127 (1474080381519003648)]} 0 0
[junit4] 2> 310830 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[127 (1474080381523197952)]} 0 0
[junit4] 2> 310834 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[128 (1474080381527392256)]} 0 0
[junit4] 2> 310838 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[128 (1474080381531586560)]} 0 0
[junit4] 2> 310842 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[129 (1474080381535780864)]} 0 0
[junit4] 2> 310846 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[129 (1474080381539975168)]} 0 0
[junit4] 2> 310850 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[130 (1474080381544169472)]} 0 0
[junit4] 2> 310854 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[130 (1474080381548363776)]} 0 0
[junit4] 2> 310858 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[131 (1474080381552558080)]} 0 0
[junit4] 2> 310863 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[131 (1474080381556752384)]} 0 1
[junit4] 2> 310867 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[132 (1474080381560946688)]} 0 1
[junit4] 2> 310871 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[132 (1474080381566189568)]} 0 1
[junit4] 2> 310875 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[133 (1474080381570383872)]} 0 0
[junit4] 2> 310879 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[133 (1474080381574578176)]} 0 0
[junit4] 2> 310883 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[134 (1474080381578772480)]} 0 0
[junit4] 2> 310887 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[134 (1474080381582966784)]} 0 0
[junit4] 2> 310891 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[135 (1474080381587161088)]} 0 1
[junit4] 2> 310895 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[135 (1474080381591355392)]} 0 0
[junit4] 2> 310898 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[136 (1474080381594501120)]} 0 0
[junit4] 2> 310901 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[136 (1474080381597646848)]} 0 0
[junit4] 2> 310905 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[137 (1474080381601841152)]} 0 0
[junit4] 2> 310909 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[137 (1474080381606035456)]} 0 1
[junit4] 2> 310912 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[138 (1474080381609181184)]} 0 0
[junit4] 2> 310915 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[138 (1474080381612326912)]} 0 0
[junit4] 2> 310917 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[139 (1474080381614424064)]} 0 0
[junit4] 2> 310920 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[139 (1474080381617569792)]} 0 0
[junit4] 2> 310924 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[140 (1474080381621764096)]} 0 0
[junit4] 2> 310928 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[140 (1474080381625958400)]} 0 0
[junit4] 2> 310932 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[141 (1474080381630152704)]} 0 0
[junit4] 2> 310937 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[141 (1474080381634347008)]} 0 1
[junit4] 2> 310941 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[142 (1474080381638541312)]} 0 1
[junit4] 2> 310945 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[142 (1474080381643784192)]} 0 1
[junit4] 2> 310949 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[143 (1474080381647978496)]} 0 1
[junit4] 2> 310953 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[143 (1474080381651124224)]} 0 1
[junit4] 2> 310957 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[144 (1474080381655318528)]} 0 1
[junit4] 2> 310960 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[144 (1474080381659512832)]} 0 0
[junit4] 2> 310964 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[145 (1474080381663707136)]} 0 0
[junit4] 2> 310968 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[145 (1474080381666852864)]} 0 1
[junit4] 2> 310972 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[146 (1474080381671047168)]} 0 1
[junit4] 2> 310975 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[146 (1474080381675241472)]} 0 0
[junit4] 2> 310980 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[147 (1474080381679435776)]} 0 1
[junit4] 2> 310983 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[147 (1474080381683630080)]} 0 0
[junit4] 2> 310987 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[148 (1474080381687824384)]} 0 0
[junit4] 2> 310991 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[148 (1474080381692018688)]} 0 0
[junit4] 2> 310995 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[149 (1474080381696212992)]} 0 0
[junit4] 2> 310999 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[149 (1474080381700407296)]} 0 1
[junit4] 2> 311004 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[500 (1474080381704601600)]} 0 1
[junit4] 2> 311007 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[500 (1474080381708795904)]} 0 0
[junit4] 2> 311011 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[501 (1474080381712990208)]} 0 0
[junit4] 2> 311015 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[501 (1474080381717184512)]} 0 1
[junit4] 2> 311018 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[502 (1474080381720330240)]} 0 0
[junit4] 2> 311022 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[502 (1474080381724524544)]} 0 0
[junit4] 2> 311026 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[503 (1474080381728718848)]} 0 1
[junit4] 2> 311030 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[503 (1474080381731864576)]} 0 1
[junit4] 2> 311034 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[504 (1474080381736058880)]} 0 1
[junit4] 2> 311038 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[504 (1474080381740253184)]} 0 0
[junit4] 2> 311041 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[505 (1474080381744447488)]} 0 0
[junit4] 2> 311045 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[505 (1474080381748641792)]} 0 1
[junit4] 2> 311048 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[506 (1474080381751787520)]} 0 0
[junit4] 2> 311052 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[506 (1474080381755981824)]} 0 0
[junit4] 2> 311056 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[507 (1474080381759127552)]} 0 1
[junit4] 2> 311059 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[507 (1474080381763321856)]} 0 0
[junit4] 2> 311063 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[508 (1474080381767516160)]} 0 0
[junit4] 2> 311067 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[508 (1474080381771710464)]} 0 0
[junit4] 2> 311070 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[509 (1474080381774856192)]} 0 0
[junit4] 2> 311074 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[509 (1474080381779050496)]} 0 0
[junit4] 2> 311077 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[510 (1474080381782196224)]} 0 0
[junit4] 2> 311081 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[510 (1474080381785341952)]} 0 1
[junit4] 2> 311084 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[511 (1474080381789536256)]} 0 0
[junit4] 2> 311088 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[511 (1474080381793730560)]} 0 0
[junit4] 2> 311092 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[512 (1474080381797924864)]} 0 0
[junit4] 2> 311096 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[512 (1474080381802119168)]} 0 0
[junit4] 2> 311100 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[513 (1474080381806313472)]} 0 0
[junit4] 2> 311103 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[513 (1474080381809459200)]} 0 0
[junit4] 2> 311106 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[514 (1474080381811556352)]} 0 1
[junit4] 2> 311108 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[514 (1474080381814702080)]} 0 0
[junit4] 2> 311112 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[515 (1474080381817847808)]} 0 1
[junit4] 2> 311115 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[515 (1474080381820993536)]} 0 1
[junit4] 2> 311118 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[516 (1474080381825187840)]} 0 1
[junit4] 2> 311121 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[516 (1474080381828333568)]} 0 0
[junit4] 2> 311124 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[517 (1474080381831479296)]} 0 0
[junit4] 2> 311127 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[517 (1474080381834625024)]} 0 0
[junit4] 2> 311130 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[518 (1474080381837770752)]} 0 0
[junit4] 2> 311132 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[518 (1474080381839867904)]} 0 0
[junit4] 2> 311135 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[519 (1474080381843013632)]} 0 0
[junit4] 2> 311139 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[519 (1474080381846159360)]} 0 1
[junit4] 2> 311142 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[520 (1474080381850353664)]} 0 0
[junit4] 2> 311146 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[520 (1474080381854547968)]} 0 1
[junit4] 2> 311149 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[521 (1474080381857693696)]} 0 0
[junit4] 2> 311154 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[521 (1474080381862936576)]} 0 0
[junit4] 2> 311158 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[522 (1474080381867130880)]} 0 0
[junit4] 2> 311161 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[522 (1474080381870276608)]} 0 0
[junit4] 2> 311163 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[523 (1474080381872373760)]} 0 0
[junit4] 2> 311166 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[523 (1474080381875519488)]} 0 0
[junit4] 2> 311169 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[524 (1474080381877616640)]} 0 1
[junit4] 2> 311172 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[524 (1474080381881810944)]} 0 0
[junit4] 2> 311174 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[525 (1474080381883908096)]} 0 0
[junit4] 2> 311178 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[525 (1474080381887053824)]} 0 1
[junit4] 2> 311180 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[526 (1474080381890199552)]} 0 0
[junit4] 2> 311183 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[526 (1474080381893345280)]} 0 0
[junit4] 2> 311185 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[527 (1474080381895442432)]} 0 0
[junit4] 2> 311189 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[527 (1474080381899636736)]} 0 1
[junit4] 2> 311192 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[528 (1474080381902782464)]} 0 0
[junit4] 2> 311194 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[528 (1474080381904879616)]} 0 0
[junit4] 2> 311197 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[529 (1474080381906976768)]} 0 1
[junit4] 2> 311200 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[529 (1474080381911171072)]} 0 1
[junit4] 2> 311202 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[530 (1474080381913268224)]} 0 0
[junit4] 2> 311204 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[530 (1474080381915365376)]} 0 0
[junit4] 2> 311206 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[531 (1474080381917462528)]} 0 0
[junit4] 2> 311209 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[531 (1474080381920608256)]} 0 0
[junit4] 2> 311212 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[532 (1474080381923753984)]} 0 0
[junit4] 2> 311215 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[532 (1474080381926899712)]} 0 0
[junit4] 2> 311218 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[533 (1474080381930045440)]} 0 0
[junit4] 2> 311220 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[533 (1474080381932142592)]} 0 0
[junit4] 2> 311224 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[534 (1474080381935288320)]} 0 1
[junit4] 2> 311226 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[534 (1474080381938434048)]} 0 0
[junit4] 2> 311228 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[535 (1474080381940531200)]} 0 0
[junit4] 2> 311230 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[535 (1474080381942628352)]} 0 0
[junit4] 2> 311233 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[536 (1474080381945774080)]} 0 0
[junit4] 2> 311236 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[536 (1474080381948919808)]} 0 0
[junit4] 2> 311239 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[537 (1474080381951016960)]} 0 1
[junit4] 2> 311241 T740 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[537 (1474080381954162688)]} 0 0
[junit4] 2> 311244 T727 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[538 (1474080381957308416)]} 0 0
[junit4] 2> 311246 T739 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[538 (1474080381959405568)]} 0 0
[junit4] 2> 311249 T728 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[539 (1474080381961502720)]} 0 1
[junit4] 2> 311251 T742 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[539 (1474080381964648448)]} 0 0
[junit4] 2> 311254 T729 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[540 (1474080381967794176)]} 0 0
[junit4] 2> 311257 T741 C2857 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[540 (1474080381970939904)]} 0 0
[junit4] 2> 311260 T730 C2858 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[541 (1474080381974085632)]} 0 0
[junit4] 2> ASYNC NEW_CORE C2859 name=collection1 org.apache.solr.core.SolrCore@1565c5d
[junit4] 2> 311274 T740 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[541 (1474080381977231360)]} 0 11
[junit4] 2> ASYNC NEW_CORE C2860 name=collection1 org.apache.solr.core.SolrCore@b0737e
[junit4] 2> 311277 T727 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[542 (1474080381991911424)]} 0 0
[junit4] 2> 311280 T739 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[542 (1474080381995057152)]} 0 0
[junit4] 2> 311283 T728 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[543 (1474080381998202880)]} 0 0
[junit4] 2> 311287 T742 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[543 (1474080382002397184)]} 0 1
[junit4] 2> 311290 T729 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[544 (1474080382005542912)]} 0 0
[junit4] 2> 311294 T741 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[544 (1474080382009737216)]} 0 0
[junit4] 2> 311298 T730 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[545 (1474080382013931520)]} 0 0
[junit4] 2> 311302 T740 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[545 (1474080382017077248)]} 0 1
[junit4] 2> 311305 T727 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[546 (1474080382021271552)]} 0 0
[junit4] 2> 311309 T739 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[546 (1474080382025465856)]} 0 0
[junit4] 2> 311312 T728 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[547 (1474080382028611584)]} 0 0
[junit4] 2> 311316 T742 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[547 (1474080382032805888)]} 0 0
[junit4] 2> 311320 T729 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[548 (1474080382035951616)]} 0 1
[junit4] 2> 311323 T740 C2859 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[548 (1474080382040145920)]} 0 0
[junit4] 2> 311327 T730 C2860 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[549 (147408038204
[...truncated too long message...]
data
[junit4] 2> 427609 T721 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard1/data/index;done=false>>]
[junit4] 2> 427610 T721 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001/tempDir-001/shard1/data/index
[junit4] 2> 427611 T721 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 432544 T721 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedGrouping -Dtests.method=testDistribSearch -Dtests.seed=B76560D5B83CA6BE -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en -Dtests.timezone=Etc/UCT -Dtests.file.encoding=UTF-8
[junit4] ERROR 124s J1 | TestDistributedGrouping.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:40022
[junit4] > at __randomizedtesting.SeedInfo.seed([B76560D5B83CA6BE:3683EECDCF63C682]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:559)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.index_specific(BaseDistributedSearchTestCase.java:487)
[junit4] > at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:140)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:152)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:122)
[junit4] > at sun.security.ssl.InputRecord.readFully(InputRecord.java:442)
[junit4] > at sun.security.ssl.InputRecord.read(InputRecord.java:480)
[junit4] > at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927)
[junit4] > at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884)
[junit4] > at sun.security.ssl.AppInputStream.read(AppInputStream.java:102)
[junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
[junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
[junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
[junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:449)
[junit4] > ... 48 more
[junit4] 2> 432570 T721 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-B76560D5B83CA6BE-001
[junit4] 2> NOTE: test params are: codec=Lucene49: {_version_=PostingsFormat(name=Memory doPackFST= false), oddField_s=Lucene41(blocksize=128), timestamp=PostingsFormat(name=Memory doPackFST= false), a_t=PostingsFormat(name=Memory doPackFST= false), n_ti1=PostingsFormat(name=Lucene41VarGapFixedInterval), foo_f=Lucene41(blocksize=128), n_tf1=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=Lucene41VarGapFixedInterval), multiDefault=Pulsing41(freqCutoff=6 minBlockSize=91 maxBlockSize=190), a_i1=PostingsFormat(name=Memory doPackFST= false), n_tl1=Pulsing41(freqCutoff=6 minBlockSize=91 maxBlockSize=190), a_s=Lucene41(blocksize=128), n_dt1=Pulsing41(freqCutoff=6 minBlockSize=91 maxBlockSize=190), intDefault=PostingsFormat(name=Memory doPackFST= false), text=Lucene41(blocksize=128), b_n_tdt=PostingsFormat(name=Memory doPackFST= false), n_f1=Pulsing41(freqCutoff=6 minBlockSize=91 maxBlockSize=190), n_d1=PostingsFormat(name=Memory doPackFST= false), n_l1=PostingsFormat(name=Memory doPackFST= false), a_n_tdt=PostingsFormat(name=Lucene41VarGapFixedInterval), other_tl1=PostingsFormat(name=Memory doPackFST= false), n_tdt1=PostingsFormat(name=Memory doPackFST= false), rnd_b=PostingsFormat(name=Lucene41VarGapFixedInterval), range_facet_l=PostingsFormat(name=Memory doPackFST= false), foo_b=Lucene41(blocksize=128), n_td1=Pulsing41(freqCutoff=6 minBlockSize=91 maxBlockSize=190), foo_d=PostingsFormat(name=Lucene41VarGapFixedInterval), SubjectTerms_mfacet=PostingsFormat(name=Memory doPackFST= false)}, docValues:{timestamp=DocValuesFormat(name=SimpleText)}, sim=DefaultSimilarity, locale=en, timezone=Etc/UCT
[junit4] 2> NOTE: Linux 3.13.0-32-generic i386/Oracle Corporation 1.7.0_65 (32-bit)/cpus=8,threads=2,free=37438656,total=96870400
[junit4] 2> NOTE: All tests run in this JVM: [CursorPagingTest, TestJmxMonitoredMap, SOLR749Test, OpenCloseCoreStressTest, TestFieldCollectionResource, FieldAnalysisRequestHandlerTest, TestCollationField, SolrCoreTest, SuggesterFSTTest, TestFiltering, SampleTest, HdfsSyncSliceTest, TestSweetSpotSimilarityFactory, CSVRequestHandlerTest, TestCSVLoader, TestNonNRTOpen, SolrPluginUtilsTest, TestReversedWildcardFilterFactory, TestCharFilters, TestQueryTypes, TestNonDefinedSimilarityFactory, TestSolrQueryParserResource, TestPHPSerializedResponseWriter, LoggingHandlerTest, TestSolrXMLSerializer, TestSchemaSimilarityResource, SolrRequestParserTest, TestUpdate, TestDistributedMissingSort, OutputWriterTest, NumericFieldsTest, TestHighFrequencyDictionaryFactory, QueryParsingTest, StatsComponentTest, TestPseudoReturnFields, MigrateRouteKeyTest, TestWriterPerf, TermVectorComponentTest, SystemInfoHandlerTest, TestSolrQueryParserDefaultOperatorResource, TestRTGBase, AlternateDirectoryTest, TestOmitPositions, TestDocumentBuilder, ScriptEngineTest, TestValueSourceCache, QueryElevationComponentTest, TestMultiCoreConfBootstrap, TestDistributedGrouping]
[junit4] Completed on J1 in 123.93s, 1 test, 1 error <<< FAILURES!
[...truncated 1077 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1298: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:922: There were test failures: 419 suites, 1712 tests, 1 error, 38 ignored (17 assumptions)
Total time: 92 minutes 31 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.7.0_65 -client -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any