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/22 16:00:56 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.7.0_65) - Build #
10870 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10870/
Java: 32bit/jdk1.7.0_65 -client -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.TestDistributedGrouping.testDistribSearch
Error Message:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://[ff01::083]:33332, http://[ff01::213]:33332, http://127.0.0.1:46078, http://[ff01::114]:33332]
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://[ff01::083]:33332, http://[ff01::213]:33332, http://127.0.0.1:46078, http://[ff01::114]:33332]
at __randomizedtesting.SeedInfo.seed([FCDE2808D5EC7F4D:7D38A610A2B31F71]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:551)
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.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:508)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:556)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:538)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:517)
at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:154)
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)
Build Log:
[...truncated 11050 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-FCDE2808D5EC7F4D-001/init-core-data-001
[junit4] 2> 256064 T474 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 256064 T474 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 256067 T474 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 256068 T474 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 256071 T474 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48412
[junit4] 2> 256072 T474 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 256073 T474 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 256073 T474 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> 256073 T474 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 256088 T474 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> 256125 T474 oasc.CoreContainer.<init> New CoreContainer 23842496
[junit4] 2> 256126 T474 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 256126 T474 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 256127 T474 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 256127 T474 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 256127 T474 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 256128 T474 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 256128 T474 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 256128 T474 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 256128 T474 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 256129 T474 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 256129 T474 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 256129 T474 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 256130 T474 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 256133 T484 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 256134 T484 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> 256134 T484 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> 256134 T484 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> 256175 T484 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 256228 T484 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 256229 T484 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 256236 T484 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 256501 T484 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 256506 T484 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 256508 T484 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 256514 T484 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 256516 T484 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 256519 T484 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 256519 T484 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 256519 T484 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 256519 T484 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 256520 T484 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 256520 T484 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 256520 T484 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> 256520 T484 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 256520 T484 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-FCDE2808D5EC7F4D-001/tempDir-001/control/data/
[junit4] 2> 256521 T484 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@a6ede2
[junit4] 2> 256521 T484 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/control/data
[junit4] 2> 256521 T484 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-FCDE2808D5EC7F4D-001/tempDir-001/control/data/index/
[junit4] 2> 256522 T484 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-FCDE2808D5EC7F4D-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 256522 T484 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/control/data/index
[junit4] 2> 256522 T484 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=26.166015625, floorSegmentMB=1.6650390625, forceMergeDeletesPctAllowed=22.31610528939945, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 256523 T484 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1cd8854 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1579446)),segFN=segments_1,generation=1}
[junit4] 2> 256523 T484 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 256523 T484 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 256526 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 256527 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 256527 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 256527 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 256527 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 256527 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 256528 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 256528 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 256528 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 256528 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 256529 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 256529 T484 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 256529 T484 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 256529 T484 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 256530 T484 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 256530 T484 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 256530 T484 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 256531 T484 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 256531 T484 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 256531 T484 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 256532 T484 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 256532 T484 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 256532 T484 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 256533 T484 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 256533 T484 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 256533 T484 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256534 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256534 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256534 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256535 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256535 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256536 T484 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256536 T484 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 256536 T484 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 256537 T484 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 256540 T484 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 256541 T484 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 256543 T484 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 256543 T484 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 256544 T484 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=117318634, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 256545 T484 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1cd8854 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1579446)),segFN=segments_1,generation=1}
[junit4] 2> 256545 T484 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 256545 T484 oass.SolrIndexSearcher.<init> Opening Searcher@16a6cfa[collection1] main
[junit4] 2> 256546 T484 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 256546 T484 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 256546 T484 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 256546 T484 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 256546 T484 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 256547 T484 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 257102 T484 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 257133 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16a6cfa[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 257138 T484 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 257139 T474 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 257139 T474 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 257140 T474 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 257150 T474 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58793
[junit4] 2> 257151 T474 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 257151 T474 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 257151 T474 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> 257152 T474 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 257167 T474 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> 257198 T474 oasc.CoreContainer.<init> New CoreContainer 14048028
[junit4] 2> 257199 T474 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 257200 T474 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 257200 T474 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 257200 T474 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 257200 T474 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 257201 T474 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 257201 T474 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 257201 T474 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 257201 T474 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 257202 T474 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 257202 T474 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 257203 T474 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 257203 T474 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 257205 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 257206 T496 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> 257206 T496 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> 257207 T496 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> 257256 T496 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 257301 T496 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 257302 T496 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 257310 T496 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 257586 T496 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 257591 T496 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 257593 T496 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 257606 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 257609 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 257611 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 257612 T496 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 257612 T496 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 257612 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 257613 T496 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 257613 T496 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 257613 T496 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> 257613 T496 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 257613 T496 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-FCDE2808D5EC7F4D-001/tempDir-001/shard0/data/
[junit4] 2> 257614 T496 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@a6ede2
[junit4] 2> 257614 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard0/data
[junit4] 2> 257615 T496 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-FCDE2808D5EC7F4D-001/tempDir-001/shard0/data/index/
[junit4] 2> 257615 T496 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-FCDE2808D5EC7F4D-001/tempDir-001/shard0/data/index' doesn't exist. Creating new index...
[junit4] 2> 257615 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard0/data/index
[junit4] 2> 257615 T496 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=26.166015625, floorSegmentMB=1.6650390625, forceMergeDeletesPctAllowed=22.31610528939945, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 257616 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@386838 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d7764)),segFN=segments_1,generation=1}
[junit4] 2> 257616 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 257616 T496 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 257620 T496 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 257620 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 257620 T496 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 257620 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 257621 T496 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 257621 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 257621 T496 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 257621 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 257621 T496 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 257622 T496 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 257622 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 257622 T496 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 257622 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 257623 T496 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 257623 T496 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 257624 T496 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 257624 T496 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 257625 T496 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 257625 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 257626 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 257626 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 257627 T496 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 257627 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 257627 T496 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 257628 T496 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 257628 T496 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257629 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257629 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257630 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257630 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257631 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257631 T496 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257632 T496 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 257632 T496 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 257633 T496 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 257636 T496 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 257637 T496 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 257639 T496 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 257639 T496 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 257641 T496 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=117318634, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 257641 T496 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@386838 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d7764)),segFN=segments_1,generation=1}
[junit4] 2> 257641 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 257642 T496 oass.SolrIndexSearcher.<init> Opening Searcher@1e24dc0[collection1] main
[junit4] 2> 257642 T496 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 257642 T496 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 257642 T496 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 257643 T496 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 257643 T496 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 257643 T496 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 257647 T496 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 257671 T497 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e24dc0[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 257679 T496 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 257680 T474 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 257680 T474 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C98 name=collection1 org.apache.solr.core.SolrCore@259927
[junit4] 2> 257696 T482 C98 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1474334366931877888)} 0 14
[junit4] 2> ASYNC NEW_CORE C99 name=collection1 org.apache.solr.core.SolrCore@ee37f2
[junit4] 2> 257700 T494 C99 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1474334366948655104)} 0 2
[junit4] 2> 257702 T483 C98 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 257702 T483 C98 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 257703 T483 C98 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 257703 T483 C98 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 257703 T483 C98 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&waitSearcher=true&softCommit=false&commit=true&version=2} {commit=} 0 1
[junit4] 2> 257706 T495 C99 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 257706 T495 C99 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 257706 T495 C99 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 257707 T495 C99 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 257707 T495 C99 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&waitSearcher=true&softCommit=false&commit=true&version=2} {commit=} 0 1
[junit4] 2> 257727 T480 C98 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&group.limit=10&fl=id,a_i1&distrib=false&group.field=a_i1&group=true&version=2&rows=100} hits=0 status=0 QTime=18
[junit4] 2> 257755 T493 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&group.limit=10&isShard=true&group.field=a_i1&distrib=false&group=true&q=*:*&NOW=1406034819587&fl=id&shard.url=http://127.0.0.1:58793/&start=0&version=2&group.distributed.first=true&rows=100} status=0 QTime=20
[junit4] 2> 257770 T492 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&shards=http://127.0.0.1:58793/&group.limit=10&fl=id,a_i1&group.field=a_i1&group=true&version=2&rows=100} status=0 QTime=39
[junit4] 2> 257793 T481 C98 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&hl.fl=a_t&group.limit=10&fl=id,a_i1&distrib=false&group.field=a_i1&group=true&version=2&hl=true&rows=100} hits=0 status=0 QTime=20
[junit4] 2> 267813 T492 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&isShard=true&group.limit=10&group.field=a_i1&distrib=false&group=true&hl=false&q=*:*&hl.fl=a_t&NOW=1406034819652&fl=id&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:58793/&start=0&version=2&group.distributed.first=true&rows=100} status=0 QTime=0
[junit4] 2> 267814 T494 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:58793/&hl.fl=a_t&group.limit=10&fl=id,a_i1&group.field=a_i1&group=true&version=2&hl=true&rows=100} status=0 QTime=10018
[junit4] 2> 267840 T480 C98 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&facet=true&group.limit=10&fl=id,a_i1&distrib=false&facet.field=a_t&group.field=a_i1&group=true&version=2&rows=100} hits=0 status=0 QTime=24
[junit4] 2> 267846 T492 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&isShard=true&facet=false&group.limit=10&facet.field=a_t&group.field=a_i1&distrib=false&group=true&q=*:*&NOW=1406034829700&fl=id&shard.url=http://127.0.0.1:58793/&start=0&version=2&group.distributed.first=true&rows=100} status=0 QTime=0
[junit4] 2> 267864 T494 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&isShard=true&facet=true&group.limit=10&group.distributed.second=true&distrib=false&facet.field=a_t&group.field=a_i1&group=true&q=*:*&NOW=1406034829700&fl=id&shard.url=http://127.0.0.1:58793/&start=0&f.a_t.facet.limit=160&version=2&rows=100} status=0 QTime=10
[junit4] 2> 267870 T493 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&shards=http://127.0.0.1:58793/&facet=true&group.limit=10&fl=id,a_i1&facet.field=a_t&group.field=a_i1&group=true&version=2&rows=100} status=0 QTime=26
[junit4] 2> 267883 T482 C98 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&stats=true&group.limit=10&fl=id,a_i1&distrib=false&group.field=a_i1&group=true&stats.field=a_i1&version=2&rows=100} hits=0 status=0 QTime=11
[junit4] 2> 267889 T492 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&stats=false&isShard=true&group.limit=10&group.field=a_i1&distrib=false&group=true&q=*:*&NOW=1406034829742&fl=id&shard.url=http://127.0.0.1:58793/&start=0&stats.field=a_i1&version=2&group.distributed.first=true&rows=100} status=0 QTime=1
[junit4] 2> 267893 T494 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&stats=true&isShard=true&group.limit=10&group.distributed.second=true&group.field=a_i1&distrib=false&group=true&q=*:*&NOW=1406034829742&fl=id&shard.url=http://127.0.0.1:58793/&start=0&stats.field=a_i1&version=2&rows=100} status=0 QTime=1
[junit4] 2> 267894 T495 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=*:*&shards=http://127.0.0.1:58793/&stats=true&group.limit=10&fl=id,a_i1&group.field=a_i1&group=true&stats.field=a_i1&version=2&rows=100} status=0 QTime=8
[junit4] 2> 267933 T483 C98 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=kings&qt=spellCheckCompRH&group.limit=10&fl=id,a_i1&distrib=false&group.field=a_i1&spellcheck=true&group=true&version=2&spellcheck.build=true&rows=100} hits=0 status=0 QTime=37
[junit4] 2> 267969 T492 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&isShard=true&group.limit=10&distrib=false&group.field=a_i1&spellcheck.extendedResults=false&spellcheck.onlyMorePopular=false&spellcheck=true&group=true&q=kings&spellcheck.count=5&NOW=1406034829793&fl=id&shard.url=[ff01::213]:33332/|http://127.0.0.1:58793/&start=0&version=2&group.distributed.first=true&spellcheck.build=true&rows=100} status=0 QTime=1
[junit4] 2> 267971 T493 C99 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/|http://127.0.0.1:58793/ null
[junit4] 2> 267971 T493 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&q=kings&shards=[ff01::213]:33332/|http://127.0.0.1:58793/&qt=spellCheckCompRH&group.limit=10&fl=id,a_i1&group.field=a_i1&spellcheck=true&group=true&version=2&spellcheck.build=true&rows=100} status=0 QTime=34
[junit4] 2> 267989 T481 C98 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&group.limit=10&facet=true&group.field=a_i1&facet.field=a_t&distrib=false&group=true&group.truncate=true&q=*:*&fl=id,a_i1&version=2&fq=a_s:a&rows=100} hits=0 status=0 QTime=15
[junit4] 2> 267994 T495 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&isShard=true&group.limit=10&facet=false&distrib=false&group.field=a_i1&facet.field=a_t&group=true&group.truncate=true&q=*:*&NOW=1406034829848&fl=id&shard.url=[ff01::213]:33332/|http://127.0.0.1:58793/|[ff01::114]:33332/|[ff01::083]:33332/&start=0&version=2&group.distributed.first=true&fq=a_s:a&rows=100} status=0 QTime=0
[junit4] 2> 267997 T492 C99 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&isShard=true&group.distributed.second=true&group.limit=10&facet=true&distrib=false&group.field=a_i1&facet.field=a_t&group=true&group.truncate=true&q=*:*&NOW=1406034829848&fl=id&shard.url=[ff01::213]:33332/|http://127.0.0.1:58793/|[ff01::114]:33332/|[ff01::083]:33332/&start=0&f.a_t.facet.limit=160&version=2&fq=a_s:a&rows=100} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C100 name=collection1 org.apache.solr.core.SolrCore@ee37f2
[junit4] 2> 268007 T494 C100 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&sort=a_i1+asc,+id+asc&group.limit=10&facet=true&group.field=a_i1&facet.field=a_t&group=true&group.truncate=true&q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:58793/|[ff01::114]:33332/|[ff01::083]:33332/&fl=id,a_i1&version=2&fq=a_s:a&rows=100} status=0 QTime=15
[junit4] 2> ASYNC NEW_CORE C101 name=collection1 org.apache.solr.core.SolrCore@259927
[junit4] 2> 268015 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1474334377762619392)]} 0 5
[junit4] 2> 268020 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1474334377769959424)]} 0 3
[junit4] 2> 268026 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1474334377775202304)]} 0 3
[junit4] 2> 268030 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1474334377780445184)]} 0 2
[junit4] 2> 268034 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1474334377785688064)]} 0 1
[junit4] 2> 268040 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1474334377790930944)]} 0 3
[junit4] 2> 268045 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1474334377796173824)]} 0 2
[junit4] 2> 268049 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1474334377800368128)]} 0 2
[junit4] 2> 268052 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1474334377804562432)]} 0 1
[junit4] 2> 268055 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1474334377807708160)]} 0 1
[junit4] 2> 268059 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1474334377811902464)]} 0 1
[junit4] 2> 268062 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1474334377816096768)]} 0 1
[junit4] 2> 268066 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1474334377819242496)]} 0 1
[junit4] 2> 268069 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1474334377822388224)]} 0 1
[junit4] 2> 268072 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1474334377825533952)]} 0 1
[junit4] 2> 268076 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1474334377829728256)]} 0 1
[junit4] 2> 268079 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1474334377833922560)]} 0 1
[junit4] 2> 268083 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1474334377837068288)]} 0 1
[junit4] 2> 268086 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1474334377840214016)]} 0 1
[junit4] 2> 268089 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1474334377844408320)]} 0 1
[junit4] 2> 268093 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1474334377847554048)]} 0 1
[junit4] 2> 268097 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1474334377851748352)]} 0 1
[junit4] 2> 268100 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1474334377854894080)]} 0 1
[junit4] 2> 268104 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1474334377859088384)]} 0 1
[junit4] 2> 268110 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1474334377864331264)]} 0 2
[junit4] 2> 268113 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1474334377869574144)]} 0 1
[junit4] 2> 268118 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1474334377873768448)]} 0 2
[junit4] 2> 268122 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1474334377879011328)]} 0 1
[junit4] 2> 268127 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1474334377883205632)]} 0 2
[junit4] 2> 268131 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1474334377887399936)]} 0 1
[junit4] 2> 268136 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1474334377891594240)]} 0 3
[junit4] 2> 268144 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1474334377896837120)]} 0 6
[junit4] 2> 268149 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18 (1474334377906274304)]} 0 1
[junit4] 2> 268153 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[18 (1474334377910468608)]} 0 2
[junit4] 2> 268157 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19 (1474334377914662912)]} 0 1
[junit4] 2> 268161 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[19 (1474334377918857216)]} 0 1
[junit4] 2> 268165 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20 (1474334377923051520)]} 0 1
[junit4] 2> 268169 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[20 (1474334377927245824)]} 0 1
[junit4] 2> 268172 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21 (1474334377930391552)]} 0 1
[junit4] 2> 268176 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[21 (1474334377934585856)]} 0 2
[junit4] 2> 268179 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22 (1474334377937731584)]} 0 1
[junit4] 2> 268182 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[22 (1474334377940877312)]} 0 1
[junit4] 2> 268185 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[23 (1474334377944023040)]} 0 1
[junit4] 2> 268188 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[23 (1474334377947168768)]} 0 1
[junit4] 2> 268191 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1474334377950314496)]} 0 1
[junit4] 2> 268194 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1474334377954508800)]} 0 1
[junit4] 2> 268197 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1474334377957654528)]} 0 0
[junit4] 2> 268200 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1474334377960800256)]} 0 0
[junit4] 2> 268203 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1474334377963945984)]} 0 0
[junit4] 2> 268206 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1474334377967091712)]} 0 1
[junit4] 2> 268209 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1474334377970237440)]} 0 1
[junit4] 2> 268212 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1474334377972334592)]} 0 1
[junit4] 2> 268215 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1474334377976528896)]} 0 1
[junit4] 2> 268219 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1474334377979674624)]} 0 1
[junit4] 2> 268223 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1474334377983868928)]} 0 2
[junit4] 2> 268226 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1474334377988063232)]} 0 1
[junit4] 2> 268229 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1474334377990160384)]} 0 1
[junit4] 2> 268232 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1474334377993306112)]} 0 1
[junit4] 2> 268235 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1474334377996451840)]} 0 1
[junit4] 2> 268237 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1474334377999597568)]} 0 0
[junit4] 2> 268240 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1474334378002743296)]} 0 0
[junit4] 2> 268243 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1474334378005889024)]} 0 1
[junit4] 2> 268247 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1474334378009034752)]} 0 1
[junit4] 2> 268249 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1474334378012180480)]} 0 0
[junit4] 2> 268252 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1474334378015326208)]} 0 0
[junit4] 2> 268255 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1474334378018471936)]} 0 0
[junit4] 2> 268259 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1474334378021617664)]} 0 1
[junit4] 2> 268262 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1474334378024763392)]} 0 1
[junit4] 2> 268265 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1474334378028957696)]} 0 0
[junit4] 2> 268268 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1474334378032103424)]} 0 1
[junit4] 2> 268271 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1474334378035249152)]} 0 1
[junit4] 2> 268274 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1474334378038394880)]} 0 1
[junit4] 2> 268277 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1474334378041540608)]} 0 0
[junit4] 2> 268280 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1474334378044686336)]} 0 0
[junit4] 2> 268284 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1474334378047832064)]} 0 1
[junit4] 2> 268287 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1474334378050977792)]} 0 1
[junit4] 2> 268290 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1474334378054123520)]} 0 1
[junit4] 2> 268293 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1474334378057269248)]} 0 1
[junit4] 2> 268296 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1474334378060414976)]} 0 1
[junit4] 2> 268298 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1474334378063560704)]} 0 0
[junit4] 2> 268301 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1474334378065657856)]} 0 1
[junit4] 2> 268304 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1474334378068803584)]} 0 1
[junit4] 2> 268306 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1474334378071949312)]} 0 0
[junit4] 2> 268309 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1474334378075095040)]} 0 0
[junit4] 2> 268312 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1474334378077192192)]} 0 1
[junit4] 2> 268316 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1474334378080337920)]} 0 2
[junit4] 2> 268319 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1474334378085580800)]} 0 0
[junit4] 2> 268322 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1474334378088726528)]} 0 1
[junit4] 2> 268325 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1474334378091872256)]} 0 1
[junit4] 2> 268327 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1474334378093969408)]} 0 0
[junit4] 2> 268330 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1474334378097115136)]} 0 0
[junit4] 2> 268333 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1474334378099212288)]} 0 1
[junit4] 2> 268336 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1474334378103406592)]} 0 1
[junit4] 2> 268339 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1474334378106552320)]} 0 1
[junit4] 2> 268342 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1474334378109698048)]} 0 1
[junit4] 2> 268345 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1474334378112843776)]} 0 0
[junit4] 2> 268349 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1474334378115989504)]} 0 1
[junit4] 2> 268352 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1474334378119135232)]} 0 1
[junit4] 2> 268355 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1474334378122280960)]} 0 1
[junit4] 2> 268358 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1474334378125426688)]} 0 1
[junit4] 2> 268361 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1474334378128572416)]} 0 0
[junit4] 2> 268363 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1474334378131718144)]} 0 0
[junit4] 2> 268366 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1474334378133815296)]} 0 1
[junit4] 2> 268369 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1474334378136961024)]} 0 1
[junit4] 2> 268372 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1474334378140106752)]} 0 1
[junit4] 2> 268375 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1474334378144301056)]} 0 0
[junit4] 2> 268378 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1474334378147446784)]} 0 0
[junit4] 2> 268381 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1474334378150592512)]} 0 1
[junit4] 2> 268384 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1474334378153738240)]} 0 1
[junit4] 2> 268387 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1474334378155835392)]} 0 1
[junit4] 2> 268389 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1474334378158981120)]} 0 0
[junit4] 2> 268392 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1474334378162126848)]} 0 1
[junit4] 2> 268395 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1474334378164224000)]} 0 1
[junit4] 2> 268397 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1474334378167369728)]} 0 0
[junit4] 2> 268400 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1474334378169466880)]} 0 1
[junit4] 2> 268402 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1474334378172612608)]} 0 0
[junit4] 2> 268405 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1474334378174709760)]} 0 1
[junit4] 2> 268408 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1474334378177855488)]} 0 1
[junit4] 2> 268411 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1474334378181001216)]} 0 1
[junit4] 2> 268413 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1474334378184146944)]} 0 0
[junit4] 2> 268417 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1474334378187292672)]} 0 1
[junit4] 2> 268420 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1474334378191486976)]} 0 0
[junit4] 2> 268424 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1474334378195681280)]} 0 1
[junit4] 2> 268427 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1474334378197778432)]} 0 1
[junit4] 2> 268429 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1474334378200924160)]} 0 0
[junit4] 2> 268432 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1474334378203021312)]} 0 1
[junit4] 2> 268434 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1474334378206167040)]} 0 0
[junit4] 2> 268437 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1474334378209312768)]} 0 1
[junit4] 2> 268440 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1474334378212458496)]} 0 1
[junit4] 2> 268443 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1474334378214555648)]} 0 1
[junit4] 2> 268445 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1474334378217701376)]} 0 0
[junit4] 2> 268448 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1474334378219798528)]} 0 1
[junit4] 2> 268450 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1474334378222944256)]} 0 0
[junit4] 2> 268453 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1474334378225041408)]} 0 1
[junit4] 2> 268455 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1474334378228187136)]} 0 0
[junit4] 2> 268458 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1474334378231332864)]} 0 1
[junit4] 2> 268463 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1474334378235527168)]} 0 2
[junit4] 2> 268467 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1474334378239721472)]} 0 1
[junit4] 2> 268471 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1474334378243915776)]} 0 1
[junit4] 2> 268474 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1474334378248110080)]} 0 1
[junit4] 2> 268478 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1474334378251255808)]} 0 1
[junit4] 2> 268481 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1474334378255450112)]} 0 0
[junit4] 2> 268485 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1474334378259644416)]} 0 1
[junit4] 2> 268488 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1474334378262790144)]} 0 0
[junit4] 2> 268491 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[500 (1474334378265935872)]} 0 0
[junit4] 2> 268494 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[500 (1474334378269081600)]} 0 0
[junit4] 2> 268497 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[501 (1474334378272227328)]} 0 0
[junit4] 2> 268500 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[501 (1474334378274324480)]} 0 1
[junit4] 2> 268502 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[502 (1474334378277470208)]} 0 0
[junit4] 2> 268505 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[502 (1474334378280615936)]} 0 0
[junit4] 2> 268507 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[503 (1474334378282713088)]} 0 0
[junit4] 2> 268510 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[503 (1474334378285858816)]} 0 1
[junit4] 2> 268513 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[504 (1474334378287955968)]} 0 1
[junit4] 2> 268515 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[504 (1474334378291101696)]} 0 0
[junit4] 2> 268517 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[505 (1474334378293198848)]} 0 0
[junit4] 2> 268520 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[505 (1474334378296344576)]} 0 1
[junit4] 2> 268522 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[506 (1474334378298441728)]} 0 0
[junit4] 2> 268525 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[506 (1474334378301587456)]} 0 1
[junit4] 2> 268527 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[507 (1474334378303684608)]} 0 0
[junit4] 2> 268530 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[507 (1474334378305781760)]} 0 1
[junit4] 2> 268532 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[508 (1474334378308927488)]} 0 0
[junit4] 2> 268535 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[508 (1474334378311024640)]} 0 1
[junit4] 2> 268537 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[509 (1474334378314170368)]} 0 0
[junit4] 2> 268540 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[509 (1474334378316267520)]} 0 1
[junit4] 2> 268542 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[510 (1474334378319413248)]} 0 0
[junit4] 2> 268544 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[510 (1474334378321510400)]} 0 0
[junit4] 2> 268546 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[511 (1474334378323607552)]} 0 0
[junit4] 2> 268549 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[511 (1474334378326753280)]} 0 1
[junit4] 2> 268551 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[512 (1474334378328850432)]} 0 0
[junit4] 2> 268554 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[512 (1474334378330947584)]} 0 1
[junit4] 2> 268556 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[513 (1474334378334093312)]} 0 0
[junit4] 2> 268559 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[513 (1474334378337239040)]} 0 1
[junit4] 2> 268561 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[514 (1474334378339336192)]} 0 0
[junit4] 2> 268564 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[514 (1474334378341433344)]} 0 1
[junit4] 2> 268566 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[515 (1474334378344579072)]} 0 0
[junit4] 2> 268569 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[515 (1474334378346676224)]} 0 1
[junit4] 2> 268572 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[516 (1474334378349821952)]} 0 1
[junit4] 2> 268574 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[516 (1474334378352967680)]} 0 0
[junit4] 2> 268577 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[517 (1474334378356113408)]} 0 0
[junit4] 2> 268580 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[517 (1474334378359259136)]} 0 1
[junit4] 2> 268583 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[518 (1474334378361356288)]} 0 1
[junit4] 2> 268585 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[518 (1474334378364502016)]} 0 0
[junit4] 2> 268588 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[519 (1474334378366599168)]} 0 1
[junit4] 2> 268590 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[519 (1474334378369744896)]} 0 0
[junit4] 2> 268592 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[520 (1474334378371842048)]} 0 0
[junit4] 2> 268595 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[520 (1474334378373939200)]} 0 1
[junit4] 2> 268597 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[521 (1474334378377084928)]} 0 0
[junit4] 2> 268599 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[521 (1474334378379182080)]} 0 0
[junit4] 2> 268602 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[522 (1474334378382327808)]} 0 0
[junit4] 2> 268604 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[522 (1474334378384424960)]} 0 0
[junit4] 2> 268607 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[523 (1474334378386522112)]} 0 1
[junit4] 2> 268609 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[523 (1474334378389667840)]} 0 0
[junit4] 2> 268612 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[524 (1474334378391764992)]} 0 1
[junit4] 2> 268614 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[524 (1474334378394910720)]} 0 0
[junit4] 2> 268617 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[525 (1474334378397007872)]} 0 1
[junit4] 2> 268619 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[525 (1474334378400153600)]} 0 0
[junit4] 2> 268622 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[526 (1474334378402250752)]} 0 1
[junit4] 2> 268624 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[526 (1474334378405396480)]} 0 0
[junit4] 2> 268626 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[527 (1474334378407493632)]} 0 0
[junit4] 2> 268629 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[527 (1474334378410639360)]} 0 0
[junit4] 2> 268631 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[528 (1474334378412736512)]} 0 0
[junit4] 2> 268634 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[528 (1474334378414833664)]} 0 1
[junit4] 2> 268636 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[529 (1474334378417979392)]} 0 0
[junit4] 2> 268639 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[529 (1474334378421125120)]} 0 1
[junit4] 2> 268642 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[530 (1474334378423222272)]} 0 1
[junit4] 2> 268644 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[530 (1474334378426368000)]} 0 0
[junit4] 2> 268646 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[531 (1474334378428465152)]} 0 0
[junit4] 2> 268649 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[531 (1474334378430562304)]} 0 1
[junit4] 2> 268651 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[532 (1474334378433708032)]} 0 0
[junit4] 2> 268654 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[532 (1474334378435805184)]} 0 1
[junit4] 2> 268656 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[533 (1474334378438950912)]} 0 0
[junit4] 2> 268658 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[533 (1474334378441048064)]} 0 0
[junit4] 2> 268661 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[534 (1474334378444193792)]} 0 0
[junit4] 2> 268663 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[534 (1474334378446290944)]} 0 0
[junit4] 2> 268666 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[535 (1474334378448388096)]} 0 1
[junit4] 2> 268669 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[535 (1474334378452582400)]} 0 1
[junit4] 2> 268673 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[536 (1474334378456776704)]} 0 0
[junit4] 2> 268676 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[536 (1474334378459922432)]} 0 0
[junit4] 2> 268679 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[537 (1474334378463068160)]} 0 1
[junit4] 2> 268681 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[537 (1474334378465165312)]} 0 0
[junit4] 2> 268684 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[538 (1474334378468311040)]} 0 0
[junit4] 2> 268687 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[538 (1474334378471456768)]} 0 1
[junit4] 2> 268689 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[539 (1474334378473553920)]} 0 0
[junit4] 2> 268692 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[539 (1474334378476699648)]} 0 1
[junit4] 2> 268694 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[540 (1474334378478796800)]} 0 0
[junit4] 2> 268696 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[540 (1474334378480893952)]} 0 0
[junit4] 2> 268699 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[541 (1474334378482991104)]} 0 1
[junit4] 2> 268701 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[541 (1474334378486136832)]} 0 0
[junit4] 2> 268703 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[542 (1474334378488233984)]} 0 0
[junit4] 2> 268706 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[542 (1474334378490331136)]} 0 1
[junit4] 2> 268708 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[543 (1474334378493476864)]} 0 0
[junit4] 2> 268710 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[543 (1474334378495574016)]} 0 0
[junit4] 2> 268713 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[544 (1474334378497671168)]} 0 1
[junit4] 2> 268715 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[544 (1474334378500816896)]} 0 0
[junit4] 2> 268718 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[545 (1474334378502914048)]} 0 1
[junit4] 2> 268720 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[545 (1474334378506059776)]} 0 0
[junit4] 2> 268723 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[546 (1474334378509205504)]} 0 0
[junit4] 2> 268725 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[546 (1474334378511302656)]} 0 0
[junit4] 2> 268728 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[547 (1474334378514448384)]} 0 1
[junit4] 2> 268730 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[547 (1474334378516545536)]} 0 0
[junit4] 2> 268733 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[548 (1474334378518642688)]} 0 1
[junit4] 2> 268735 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[548 (1474334378521788416)]} 0 0
[junit4] 2> 268738 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[549 (1474334378523885568)]} 0 1
[junit4] 2> 268741 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[549 (1474334378527031296)]} 0 1
[junit4] 2> 268743 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[550 (1474334378530177024)]} 0 0
[junit4] 2> 268746 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[550 (1474334378532274176)]} 0 1
[junit4] 2> 268748 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[551 (1474334378535419904)]} 0 0
[junit4] 2> 268751 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[551 (1474334378537517056)]} 0 1
[junit4] 2> 268753 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[552 (1474334378540662784)]} 0 0
[junit4] 2> 268755 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[552 (1474334378542759936)]} 0 0
[junit4] 2> 268758 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[553 (1474334378545905664)]} 0 1
[junit4] 2> 268761 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[553 (1474334378549051392)]} 0 0
[junit4] 2> 268764 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[554 (1474334378552197120)]} 0 0
[junit4] 2> 268767 T495 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[554 (1474334378554294272)]} 0 1
[junit4] 2> 268772 T483 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[555 (1474334378557440000)]} 0 3
[junit4] 2> 268778 T494 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[555 (1474334378563731456)]} 0 3
[junit4] 2> 268781 T481 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[556 (1474334378570022912)]} 0 1
[junit4] 2> 268785 T492 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[556 (1474334378573168640)]} 0 1
[junit4] 2> 268788 T482 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[557 (1474334378577362944)]} 0 0
[junit4] 2> 268792 T493 C100 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[557 (1474334378580508672)]} 0 1
[junit4] 2> 268795 T480 C101 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[558 (1474334378583654400)]} 0 1
[junit4] 2> 268797 T495
[...truncated too long message...]
e /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard1/data;done=false>>]
[junit4] 2> 551846 T474 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard1/data
[junit4] 2> 551846 T474 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-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-FCDE2808D5EC7F4D-001/tempDir-001/shard1/data/index;done=false>>]
[junit4] 2> 551847 T474 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard1/data/index
[junit4] 2> 551848 T474 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 556844 T474 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=31401665
[junit4] 2> 556845 T474 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d6762f
[junit4] 2> 556861 T474 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=124,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=11345,transaction_logs_total_number=1}
[junit4] 2> 556862 T474 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 556862 T474 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 556863 T474 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 556864 T474 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 556865 T474 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 556865 T474 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard2/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard2/data;done=false>>]
[junit4] 2> 556865 T474 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard2/data
[junit4] 2> 556866 T474 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard2/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard2/data/index;done=false>>]
[junit4] 2> 556866 T474 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.TestDistributedGrouping-FCDE2808D5EC7F4D-001/tempDir-001/shard2/data/index
[junit4] 2> 556867 T474 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 561834 T474 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedGrouping -Dtests.method=testDistribSearch -Dtests.seed=FCDE2808D5EC7F4D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_SA -Dtests.timezone=America/Nipigon -Dtests.file.encoding=US-ASCII
[junit4] ERROR 306s J1 | TestDistributedGrouping.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://[ff01::083]:33332, http://[ff01::213]:33332, http://127.0.0.1:46078, http://[ff01::114]:33332]
[junit4] > at __randomizedtesting.SeedInfo.seed([FCDE2808D5EC7F4D:7D38A610A2B31F71]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:551)
[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.QueryRequest.process(QueryRequest.java:91)
[junit4] > at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:508)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:556)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:538)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:517)
[junit4] > at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:154)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 561859 T474 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-FCDE2808D5EC7F4D-001
[junit4] 2> NOTE: test params are: codec=Lucene49: {n_f1=PostingsFormat(name=Lucene41VarGapFixedInterval), n_tf1=PostingsFormat(name=MockRandom), _version_=PostingsFormat(name=Lucene41VarGapFixedInterval), rnd_b=Pulsing41(freqCutoff=1 minBlockSize=60 maxBlockSize=208), intDefault=PostingsFormat(name=Lucene41VarGapFixedInterval), timestamp=PostingsFormat(name=MockRandom), foo_f=PostingsFormat(name=SimpleText), other_tl1=PostingsFormat(name=Lucene41VarGapFixedInterval), a_i1=PostingsFormat(name=MockRandom), n_td1=PostingsFormat(name=Lucene41VarGapFixedInterval), n_l1=PostingsFormat(name=MockRandom), SubjectTerms_mfacet=PostingsFormat(name=Lucene41VarGapFixedInterval), text=PostingsFormat(name=SimpleText), n_tdt1=PostingsFormat(name=Lucene41VarGapFixedInterval), range_facet_l=PostingsFormat(name=MockRandom), id=Pulsing41(freqCutoff=1 minBlockSize=60 maxBlockSize=208), multiDefault=PostingsFormat(name=MockRandom), b_n_tdt=PostingsFormat(name=Lucene41VarGapFixedInterval), a_n_tdt=PostingsFormat(name=SimpleText), foo_b=PostingsFormat(name=SimpleText), a_t=PostingsFormat(name=MockRandom), n_dt1=PostingsFormat(name=Lucene41VarGapFixedInterval), n_d1=PostingsFormat(name=MockRandom), n_ti1=Pulsing41(freqCutoff=1 minBlockSize=60 maxBlockSize=208), foo_d=Pulsing41(freqCutoff=1 minBlockSize=60 maxBlockSize=208), oddField_s=Pulsing41(freqCutoff=1 minBlockSize=60 maxBlockSize=208), a_s=PostingsFormat(name=SimpleText), n_tl1=PostingsFormat(name=Lucene41VarGapFixedInterval)}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity, locale=ar_SA, timezone=America/Nipigon
[junit4] 2> NOTE: Linux 3.13.0-32-generic i386/Oracle Corporation 1.7.0_65 (32-bit)/cpus=8,threads=2,free=39673480,total=140767232
[junit4] 2> NOTE: All tests run in this JVM: [TermVectorComponentDistributedTest, TermVectorComponentTest, TestBinaryField, TestRequestStatusCollectionAPI, TestOverriddenPrefixQueryForCustomFieldType, TestRecoveryHdfs, TestCollationField, LeaderElectionIntegrationTest, TestDistributedGrouping]
[junit4] Completed on J1 in 305.82s, 1 test, 1 error <<< FAILURES!
[...truncated 1231 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, 1733 tests, 1 error, 39 ignored (18 assumptions)
Total time: 93 minutes 49 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