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/02/06 18:26:40 UTC

[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-fcs-b128) - Build # 9278 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/9278/
Java: 32bit/jdk1.8.0-fcs-b128 -server -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.solr.TestDistributedSearch.testDistribSearch

Error Message:
Expected the partialResults header to be null

Stack Trace:
java.lang.AssertionError: Expected the partialResults header to be null
	at __randomizedtesting.SeedInfo.seed([E8407FD57D3AB934:69A6F1CD0A65D908]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertNull(Assert.java:551)
	at org.apache.solr.TestDistributedSearch.validateControlData(TestDistributedSearch.java:525)
	at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:445)
	at org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:379)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:873)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:483)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	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:70)
	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:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	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 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:70)
	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:358)
	at java.lang.Thread.run(Thread.java:744)




Build Log:
[...truncated 10564 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> 873532 T5340 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /rw_beu/b
   [junit4]   2> 873535 T5340 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 873536 T5340 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 873538 T5340 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59251
   [junit4]   2> 873540 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 873540 T5340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 873541 T5340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
   [junit4]   2> 873541 T5340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 873557 T5340 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 873577 T5340 oasc.CoreContainer.<init> New CoreContainer 6114172
   [junit4]   2> 873577 T5340 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 873578 T5340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 873578 T5340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 873579 T5340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 873579 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 873580 T5340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 873580 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 873581 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 873581 T5340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 873581 T5340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 873584 T5340 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 873584 T5340 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 873585 T5340 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 873587 T5350 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 873588 T5350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 873588 T5350 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 873588 T5350 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 873609 T5350 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 873629 T5350 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 873630 T5350 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 873634 T5350 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 873748 T5350 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 873749 T5350 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 873749 T5350 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 873754 T5350 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 873755 T5350 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 873761 T5350 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 873762 T5350 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 873763 T5350 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 873764 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 873764 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 873764 T5350 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 873764 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 873765 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 873765 T5350 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 873765 T5350 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.TestDistributedSearch-1391707147168/control/data/
   [junit4]   2> 873765 T5350 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b37a55
   [junit4]   2> 873766 T5350 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/control/data
   [junit4]   2> 873766 T5350 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1391707147168/control/data/index/
   [junit4]   2> 873766 T5350 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1391707147168/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 873766 T5350 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/control/data/index
   [junit4]   2> 873767 T5350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 873767 T5350 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_1,generation=1}
   [junit4]   2> 873767 T5350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 873767 T5350 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 873769 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 873769 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 873769 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 873770 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 873770 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 873770 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 873770 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 873770 T5350 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 873770 T5350 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 873771 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 873771 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 873771 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 873771 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 873771 T5350 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 873777 T5350 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 873778 T5350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 873779 T5350 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 873780 T5350 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 873780 T5350 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 873781 T5350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=77.357421875, floorSegmentMB=1.74609375, forceMergeDeletesPctAllowed=14.463724565465968, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6060744454666547
   [junit4]   2> 873781 T5350 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_1,generation=1}
   [junit4]   2> 873781 T5350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 873781 T5350 oass.SolrIndexSearcher.<init> Opening Searcher@18f9f05[collection1] main
   [junit4]   2> 873782 T5350 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 873895 T5350 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 873918 T5351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18f9f05[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 873921 T5350 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 873922 T5340 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 873922 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 873924 T5340 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 873925 T5340 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:42626
   [junit4]   2> 873926 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 873926 T5340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 873927 T5340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
   [junit4]   2> 873927 T5340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 873942 T5340 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 873952 T5340 oasc.CoreContainer.<init> New CoreContainer 9916478
   [junit4]   2> 873952 T5340 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 873953 T5340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 873954 T5340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 873954 T5340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 873954 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 873955 T5340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 873955 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 873956 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 873956 T5340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 873957 T5340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 873959 T5340 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 873960 T5340 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 873960 T5340 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 873962 T5362 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 873962 T5362 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 873964 T5362 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 873964 T5362 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 874000 T5362 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 874021 T5362 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 874022 T5362 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 874026 T5362 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 874167 T5362 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 874167 T5362 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 874167 T5362 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 874171 T5362 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 874172 T5362 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 874176 T5362 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 874177 T5362 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 874178 T5362 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 874179 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 874179 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 874179 T5362 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 874179 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 874180 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 874180 T5362 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 874180 T5362 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/
   [junit4]   2> 874180 T5362 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b37a55
   [junit4]   2> 874181 T5362 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data
   [junit4]   2> 874181 T5362 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index/
   [junit4]   2> 874181 T5362 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index' doesn't exist. Creating new index...
   [junit4]   2> 874181 T5362 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index
   [junit4]   2> 874182 T5362 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 874182 T5362 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_1,generation=1}
   [junit4]   2> 874182 T5362 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 874183 T5362 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 874184 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 874185 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 874185 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 874185 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 874185 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 874185 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 874185 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 874186 T5362 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 874186 T5362 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 874186 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 874186 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 874186 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 874187 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 874187 T5362 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 874187 T5362 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 874187 T5362 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 874190 T5362 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 874190 T5362 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 874190 T5362 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874192 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874192 T5362 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874192 T5362 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 874193 T5362 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 874193 T5362 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 874194 T5362 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 874195 T5362 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 874196 T5362 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 874197 T5362 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 874197 T5362 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=77.357421875, floorSegmentMB=1.74609375, forceMergeDeletesPctAllowed=14.463724565465968, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6060744454666547
   [junit4]   2> 874198 T5362 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_1,generation=1}
   [junit4]   2> 874198 T5362 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 874199 T5362 oass.SolrIndexSearcher.<init> Opening Searcher@142f062[collection1] main
   [junit4]   2> 874199 T5362 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 874203 T5362 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 874219 T5363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@142f062[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 874222 T5362 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 874223 T5340 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 874223 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C1813 name=collection1 org.apache.solr.core.SolrCore@1dd47
   [junit4]   2> 874225 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1459310714272350208)} 0 0
   [junit4]   2> ASYNC  NEW_CORE C1814 name=collection1 org.apache.solr.core.SolrCore@b4c1dd
   [junit4]   2> 874227 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1459310714273398784)} 0 1
   [junit4]   2> 874231 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1 (1459310714276544512)]} 0 2
   [junit4]   2> 874234 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1 (1459310714280738816)]} 0 1
   [junit4]   2> 874237 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[2 (1459310714283884544)]} 0 1
   [junit4]   2> 874239 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[2 (1459310714287030272)]} 0 0
   [junit4]   2> 874242 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[3 (1459310714289127424)]} 0 1
   [junit4]   2> 874245 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[3 (1459310714292273152)]} 0 1
   [junit4]   2> 874247 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[4 (1459310714295418880)]} 0 0
   [junit4]   2> 874250 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[4 (1459310714297516032)]} 0 1
   [junit4]   2> 874252 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[5 (1459310714300661760)]} 0 0
   [junit4]   2> 874255 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[5 (1459310714302758912)]} 0 1
   [junit4]   2> 874258 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[6 (1459310714305904640)]} 0 1
   [junit4]   2> 874260 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[6 (1459310714309050368)]} 0 0
   [junit4]   2> 874263 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[7 (1459310714311147520)]} 0 1
   [junit4]   2> 874266 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[7 (1459310714314293248)]} 0 1
   [junit4]   2> 874268 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[8 (1459310714317438976)]} 0 0
   [junit4]   2> 874271 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[8 (1459310714319536128)]} 0 1
   [junit4]   2> 874273 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[9 (1459310714321633280)]} 0 1
   [junit4]   2> 874276 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[9 (1459310714324779008)]} 0 1
   [junit4]   2> 874278 T5346 C1813 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 874283 T5346 C1813 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_2,generation=2}
   [junit4]   2> 874284 T5346 C1813 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 874285 T5346 C1813 oass.SolrIndexSearcher.<init> Opening Searcher@1e2b085[collection1] main
   [junit4]   2> 874286 T5346 C1813 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 874287 T5351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e2b085[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):C9)}
   [junit4]   2> 874288 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 10
   [junit4]   2> 874290 T5360 C1814 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 874293 T5360 C1814 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_2,generation=2}
   [junit4]   2> 874294 T5360 C1814 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 874296 T5360 C1814 oass.SolrIndexSearcher.<init> Opening Searcher@e746ef[collection1] main
   [junit4]   2> 874297 T5363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e746ef[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):C9)}
   [junit4]   2> 874298 T5360 C1814 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 874298 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 8
   [junit4]   2> 874302 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[10 (1459310714350993408)]} 0 2
   [junit4]   2> 874307 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[10 (1459310714356236288)]} 0 3
   [junit4]   2> 874309 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[11 (1459310714360430592)]} 0 0
   [junit4]   2> 874312 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[11 (1459310714363576320)]} 0 0
   [junit4]   2> 874315 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[12 (1459310714366722048)]} 0 0
   [junit4]   2> 874318 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[12 (1459310714368819200)]} 0 1
   [junit4]   2> 874320 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[13 (1459310714371964928)]} 0 0
   [junit4]   2> 874323 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[13 (1459310714374062080)]} 0 1
   [junit4]   2> 874326 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1001 (1459310714377207808)]} 0 1
   [junit4]   2> 874329 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1001 (1459310714380353536)]} 0 1
   [junit4]   2> 874331 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[14 (1459310714383499264)]} 0 0
   [junit4]   2> 874334 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[14 (1459310714385596416)]} 0 1
   [junit4]   2> 874336 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[15 (1459310714388742144)]} 0 0
   [junit4]   2> 874339 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[15 (1459310714390839296)]} 0 1
   [junit4]   2> 874341 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[16 (1459310714392936448)]} 0 1
   [junit4]   2> 874344 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[16 (1459310714396082176)]} 0 1
   [junit4]   2> 874348 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[17 (1459310714399227904)]} 0 2
   [junit4]   2> 874352 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[17 (1459310714403422208)]} 0 2
   [junit4]   2> 874354 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[100 (1459310714407616512)]} 0 0
   [junit4]   2> 874357 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[100 (1459310714409713664)]} 0 1
   [junit4]   2> 874359 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[101 (1459310714412859392)]} 0 0
   [junit4]   2> 874362 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[101 (1459310714414956544)]} 0 1
   [junit4]   2> 874365 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[102 (1459310714418102272)]} 0 1
   [junit4]   2> 874367 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[102 (1459310714421248000)]} 0 0
   [junit4]   2> 874370 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[103 (1459310714424393728)]} 0 0
   [junit4]   2> 874373 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[103 (1459310714426490880)]} 0 1
   [junit4]   2> 874375 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[104 (1459310714429636608)]} 0 0
   [junit4]   2> 874377 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[104 (1459310714431733760)]} 0 0
   [junit4]   2> 874380 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[105 (1459310714434879488)]} 0 1
   [junit4]   2> 874382 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[105 (1459310714436976640)]} 0 0
   [junit4]   2> 874385 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[106 (1459310714439073792)]} 0 1
   [junit4]   2> 874387 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[106 (1459310714442219520)]} 0 0
   [junit4]   2> 874390 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[107 (1459310714444316672)]} 0 1
   [junit4]   2> 874392 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[107 (1459310714447462400)]} 0 0
   [junit4]   2> 874395 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[108 (1459310714449559552)]} 0 1
   [junit4]   2> 874397 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[108 (1459310714452705280)]} 0 1
   [junit4]   2> 874399 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[109 (1459310714454802432)]} 0 0
   [junit4]   2> 874402 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[109 (1459310714456899584)]} 0 1
   [junit4]   2> 874404 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[110 (1459310714460045312)]} 0 0
   [junit4]   2> 874406 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[110 (1459310714462142464)]} 0 0
   [junit4]   2> 874409 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[111 (1459310714464239616)]} 0 1
   [junit4]   2> 874411 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[111 (1459310714467385344)]} 0 1
   [junit4]   2> 874413 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[112 (1459310714469482496)]} 0 0
   [junit4]   2> 874416 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[112 (1459310714471579648)]} 0 1
   [junit4]   2> 874418 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[113 (1459310714474725376)]} 0 0
   [junit4]   2> 874421 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[113 (1459310714476822528)]} 0 1
   [junit4]   2> 874423 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[114 (1459310714478919680)]} 0 1
   [junit4]   2> 874425 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[114 (1459310714482065408)]} 0 0
   [junit4]   2> 874428 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[115 (1459310714484162560)]} 0 1
   [junit4]   2> 874430 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[115 (1459310714487308288)]} 0 1
   [junit4]   2> 874432 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[116 (1459310714489405440)]} 0 0
   [junit4]   2> 874435 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[116 (1459310714491502592)]} 0 1
   [junit4]   2> 874437 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[117 (1459310714494648320)]} 0 0
   [junit4]   2> 874440 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[117 (1459310714496745472)]} 0 1
   [junit4]   2> 874442 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[118 (1459310714499891200)]} 0 0
   [junit4]   2> 874445 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[118 (1459310714501988352)]} 0 1
   [junit4]   2> 874447 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[119 (1459310714505134080)]} 0 1
   [junit4]   2> 874449 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[119 (1459310714507231232)]} 0 0
   [junit4]   2> 874452 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[120 (1459310714509328384)]} 0 1
   [junit4]   2> 874454 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[120 (1459310714512474112)]} 0 0
   [junit4]   2> 874457 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[121 (1459310714514571264)]} 0 1
   [junit4]   2> 874459 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[121 (1459310714517716992)]} 0 0
   [junit4]   2> 874462 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[122 (1459310714519814144)]} 0 1
   [junit4]   2> 874464 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[122 (1459310714521911296)]} 0 1
   [junit4]   2> 874466 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[123 (1459310714525057024)]} 0 0
   [junit4]   2> 874469 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[123 (1459310714527154176)]} 0 1
   [junit4]   2> 874471 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[124 (1459310714530299904)]} 0 0
   [junit4]   2> 874473 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[124 (1459310714532397056)]} 0 0
   [junit4]   2> 874476 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[125 (1459310714534494208)]} 0 1
   [junit4]   2> 874478 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[125 (1459310714537639936)]} 0 1
   [junit4]   2> 874481 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[126 (1459310714540785664)]} 0 0
   [junit4]   2> 874484 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[126 (1459310714542882816)]} 0 1
   [junit4]   2> 874487 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[127 (1459310714546028544)]} 0 1
   [junit4]   2> 874489 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[127 (1459310714549174272)]} 0 1
   [junit4]   2> 874491 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[128 (1459310714551271424)]} 0 0
   [junit4]   2> 874494 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[128 (1459310714553368576)]} 0 1
   [junit4]   2> 874496 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[129 (1459310714556514304)]} 0 0
   [junit4]   2> 874498 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[129 (1459310714558611456)]} 0 0
   [junit4]   2> 874500 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[130 (1459310714560708608)]} 0 0
   [junit4]   2> 874502 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[130 (1459310714562805760)]} 0 0
   [junit4]   2> 874504 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[131 (1459310714564902912)]} 0 0
   [junit4]   2> 874506 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[131 (1459310714567000064)]} 0 0
   [junit4]   2> 874509 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[132 (1459310714569097216)]} 0 1
   [junit4]   2> 874511 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[132 (1459310714572242944)]} 0 0
   [junit4]   2> 874514 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[133 (1459310714574340096)]} 0 1
   [junit4]   2> 874516 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[133 (1459310714577485824)]} 0 0
   [junit4]   2> 874519 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[134 (1459310714580631552)]} 0 1
   [junit4]   2> 874521 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[134 (1459310714582728704)]} 0 0
   [junit4]   2> 874532 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[135 (1459310714593214464)]} 0 1
   [junit4]   2> 874536 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[135 (1459310714597408768)]} 0 1
   [junit4]   2> 874538 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[136 (1459310714600554496)]} 0 0
   [junit4]   2> 874541 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[136 (1459310714603700224)]} 0 1
   [junit4]   2> 874544 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[137 (1459310714605797376)]} 0 1
   [junit4]   2> 874546 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[137 (1459310714608943104)]} 0 0
   [junit4]   2> 874549 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[138 (1459310714611040256)]} 0 1
   [junit4]   2> 874551 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[138 (1459310714613137408)]} 0 1
   [junit4]   2> 874560 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[139 (1459310714622574592)]} 0 1
   [junit4]   2> 874564 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[139 (1459310714626768896)]} 0 1
   [junit4]   2> 874567 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[140 (1459310714629914624)]} 0 1
   [junit4]   2> 874569 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[140 (1459310714633060352)]} 0 0
   [junit4]   2> 874572 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[141 (1459310714635157504)]} 0 1
   [junit4]   2> 874574 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[141 (1459310714638303232)]} 0 0
   [junit4]   2> 874577 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[142 (1459310714640400384)]} 0 1
   [junit4]   2> 874579 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[142 (1459310714643546112)]} 0 0
   [junit4]   2> 874582 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[143 (1459310714645643264)]} 0 1
   [junit4]   2> 874584 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[143 (1459310714648788992)]} 0 0
   [junit4]   2> 874587 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[144 (1459310714650886144)]} 0 1
   [junit4]   2> 874608 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[144 (1459310714672906240)]} 0 1
   [junit4]   2> 874610 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[145 (1459310714676051968)]} 0 0
   [junit4]   2> 874613 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[145 (1459310714679197696)]} 0 0
   [junit4]   2> 874616 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[146 (1459310714681294848)]} 0 1
   [junit4]   2> 874618 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[146 (1459310714684440576)]} 0 0
   [junit4]   2> 874620 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[147 (1459310714686537728)]} 0 0
   [junit4]   2> 874623 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[147 (1459310714688634880)]} 0 1
   [junit4]   2> 874625 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[148 (1459310714691780608)]} 0 0
   [junit4]   2> 874627 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[148 (1459310714693877760)]} 0 0
   [junit4]   2> 874630 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[149 (1459310714695974912)]} 0 1
   [junit4]   2> 874632 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[149 (1459310714699120640)]} 0 0
   [junit4]   2> 874634 T5347 C1813 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 874645 T5347 C1813 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_3,generation=3}
   [junit4]   2> 874646 T5347 C1813 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 874647 T5347 C1813 oass.SolrIndexSearcher.<init> Opening Searcher@1ea50e9[collection1] main
   [junit4]   2> 874648 T5347 C1813 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 874649 T5351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ea50e9[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(4.7):C9 _1(4.7):C59)}
   [junit4]   2> 874650 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 16
   [junit4]   2> 874652 T5359 C1814 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> ASYNC  NEW_CORE C1815 name=collection1 org.apache.solr.core.SolrCore@b4c1dd
   [junit4]   2> 874675 T5359 C1815 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_3,generation=3}
   [junit4]   2> 874676 T5359 C1815 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 874677 T5359 C1815 oass.SolrIndexSearcher.<init> Opening Searcher@8e7ca1[collection1] main
   [junit4]   2> 874677 T5359 C1815 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 874679 T5363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8e7ca1[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(4.7):C9 _1(4.7):C59)}
   [junit4]   2> 874679 T5359 C1815 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 27
   [junit4]   2> ASYNC  NEW_CORE C1816 name=collection1 org.apache.solr.core.SolrCore@1dd47
   [junit4]   2> 874682 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 874897 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148318&start=0&fsv=true&isShard=true&sort=n_ti1+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 874906 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148318&ids=146,124,103,147,136,17,128,8,119,108&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 874910 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=225 
   [junit4]   2> 874912 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 874917 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148548&start=0&fsv=true&isShard=true&sort=n_ti1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 874920 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148548&ids=11,143,144,100,13,4,137,126,107,109&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 874923 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8 
   [junit4]   2> 874926 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 874930 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148561&start=0&fsv=true&isShard=true&sort=n_f1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 874933 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148561&ids=144,133,112,123,101,15,148,149,1001,107&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 874935 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7 
   [junit4]   2> 874937 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875141 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148573&start=0&fsv=true&isShard=true&sort=n_f1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875144 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148573&ids=1,13,2,4,5,115,128,8,10,120&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875145 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=205 
   [junit4]   2> 875148 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875153 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148784&start=0&fsv=true&isShard=true&sort=n_tf1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875155 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148784&ids=1,14,15,148,16,126,1001,7,129,107&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875157 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875159 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875164 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148795&start=0&fsv=true&isShard=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875167 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148795&ids=110,121,143,13,113,4,128,139,140,130&isShard=true&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 875168 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875171 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875176 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148807&start=0&fsv=true&isShard=true&sort=n_d1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875179 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148807&ids=121,122,12,113,114,136,105,118,140,142&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875180 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875182 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875186 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148818&start=0&fsv=true&isShard=true&sort=n_d1+asc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875188 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148818&ids=110,135,125,115,5,104,127,7,10,131&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875190 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5 
   [junit4]   2> 875192 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875461 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148827&start=0&fsv=true&isShard=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875464 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148827&ids=132,122,101,125,5,116,138,128,139,131&isShard=true&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 875465 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=271 
   [junit4]   2> 875468 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875472 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149103&start=0&fsv=true&isShard=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875475 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149103&ids=144,112,3,124,136,103,149,17,119,142&isShard=true&shard.url=[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 875476 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875478 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875483 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149114&start=0&fsv=true&isShard=true&sort=n_l1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875485 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149114&ids=123,13,14,102,4,5,138,6,116,8&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875486 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5 
   [junit4]   2> 875489 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875493 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149124&start=0&fsv=true&isShard=true&sort=n_l1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875495 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149124&ids=110,144,111,125,114,126,1001,129,119,10&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875496 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5 
   [junit4]   2> 875499 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875503 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149134&start=0&fsv=true&isShard=true&sort=n_tl1+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875508 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149134&ids=143,11,133,124,114,4,126,115,9,130&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 875509 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8 
   [junit4]   2> 875512 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875516 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149147&start=0&fsv=true&isShard=true&sort=n_tl1+asc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875519 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149147&ids=122,134,136,149,105,17,7,1001,141,142&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875520 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875523 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875528 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149159&start=0&fsv=true&isShard=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875533 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149159&ids=11,100,112,145,101,113,135,128,106,130&isShard=true&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 875534 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8 
   [junit4]   2> 875536 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875541 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149172&start=0&fsv=true&isShard=true&sort=n_dt1+asc&shard.url=[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875544 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149172&ids=143,102,103,125,7,1001,117,129,8,107&isShard=true&shard.url=[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875545 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875548 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875552 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149183&start=0&fsv=true&isShard=true&sort=n_tdt1+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875555 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149183&ids=100,113,137,104,6,139,8,107,9,130&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 875556 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875558 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875563 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149194&start=0&fsv=true&isShard=true&sort=n_tdt1+asc&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875566 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149194&ids=110,143,144,133,101,14,136,7,108,131&isShard=true&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 875567 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875570 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875577 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149208&start=0&fsv=true&isShard=true&sort=a_si+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875580 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149208&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875581 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875588 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_si,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875593 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149224&start=0&fsv=true&isShard=true&sort={!func}testfunc(add(a_si,5))+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875605 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149224&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875606 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort={!func}testfunc(add(a_si,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=15 
   [junit4]   2> 875610 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=a_si+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875615 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149246&start=0&fsv=true&isShard=true&sort=a_si+asc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875618 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149246&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875619 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=a_si+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875622 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875627 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id,score&NOW=1391707149258&start=0&fsv=true&isShard=true&sort=a_si+desc&shard.url=[ff01::083]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875630 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&NOW=1391707149258&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875631 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::083]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&fl=*,score&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 
   [junit4]   2> 875634 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 
   [junit4]   2> 875638 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id,score&NOW=1391707149269&start=0&fsv=true&isShard=true&sort=n_tl1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0 
   [junit4]   2> 875640 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&NOW=1391707149269&ids=122,134,136,149,105,17,7,1001,141,142&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> 875641 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/sele

[...truncated too long message...]

40 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index;done=false>>]
   [junit4]   2> 887932 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index
   [junit4]   2> 887934 T5340 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/rw_beu/b,null}
   [junit4]   2> 888005 T5340 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17848161
   [junit4]   2> 888006 T5340 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@bdc0aa
   [junit4]   2> 888015 T5340 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=25,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=6959,transaction_logs_total_number=3}
   [junit4]   2> 888015 T5340 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 888016 T5340 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 888016 T5340 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 888019 T5340 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 888020 T5340 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 888020 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data/index;done=false>>]
   [junit4]   2> 888021 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data/index
   [junit4]   2> 888021 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data;done=false>>]
   [junit4]   2> 888022 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data
   [junit4]   2> 888023 T5340 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/rw_beu/b,null}
   [junit4]   2> 888095 T5340 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26665611
   [junit4]   2> 888096 T5340 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@196f9c
   [junit4]   2> 888110 T5340 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=23,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=5719,transaction_logs_total_number=3}
   [junit4]   2> 888111 T5340 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 888111 T5340 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 888112 T5340 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 888119 T5340 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 888120 T5340 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 888120 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data/index;done=false>>]
   [junit4]   2> 888121 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data/index
   [junit4]   2> 888131 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data;done=false>>]
   [junit4]   2> 888132 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data
   [junit4]   2> 888133 T5340 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/rw_beu/b,null}
   [junit4]   2> 888205 T5340 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDistributedSearch -Dtests.method=testDistribSearch -Dtests.seed=E8407FD57D3AB934 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_CL -Dtests.timezone=Australia/Lord_Howe -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 14.7s J0 | TestDistributedSearch.testDistribSearch <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected the partialResults header to be null
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([E8407FD57D3AB934:69A6F1CD0A65D908]:0)
   [junit4]    > 	at org.apache.solr.TestDistributedSearch.validateControlData(TestDistributedSearch.java:525)
   [junit4]    > 	at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:445)
   [junit4]    > 	at org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:379)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:873)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 888242 T5340 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Lucene45, sim=DefaultSimilarity, locale=es_CL, timezone=Australia/Lord_Howe
   [junit4]   2> NOTE: Linux 3.8.0-35-generic i386/Oracle Corporation 1.8.0 (32-bit)/cpus=8,threads=2,free=50053312,total=269234176
   [junit4]   2> NOTE: All tests run in this JVM: [ConnectionManagerTest, LegacyHTMLStripCharFilterTest, TestPseudoReturnFields, TestDocBasedVersionConstraints, DistributedSpellCheckComponentTest, TestCoreContainer, DistributedSuggestComponentTest, LeaderElectionTest, ZkCLITest, FastVectorHighlighterTest, TestDynamicFieldResource, TestSuggestSpellingConverter, TestLFUCache, SliceStateTest, OverseerCollectionProcessorTest, LukeRequestHandlerTest, TestRemoteStreaming, SoftAutoCommitTest, TestQueryUtils, AutoCommitTest, TestSearchPerf, StatelessScriptUpdateProcessorFactoryTest, AnalysisErrorHandlingTest, CacheHeaderTest, TestRandomMergePolicy, AliasIntegrationTest, CoreAdminHandlerTest, UnloadDistributedZkTest, TestStandardQParsers, TestSolrQueryParserResource, DeleteShardTest, ResourceLoaderTest, QueryEqualityTest, TestElisionMultitermQuery, UpdateRequestProcessorFactoryTest, AddBlockUpdateTest, TestCharFilters, TestJmxMonitoredMap, TestFieldCollectionResource, SystemInfoHandlerTest, HdfsUnloadDistributedZkTest, PolyFieldTest, ExternalFileFieldSortTest, TestSurroundQueryParser, TestPostingsSolrHighlighter, PrimitiveFieldTypeTest, TestFunctionQuery, SuggesterTest, TestReplicationHandler, TestExtendedDismaxParser, SimpleFacetsTest, MoreLikeThisHandlerTest, TestDocumentBuilder, TestNonNRTOpen, TestPHPSerializedResponseWriter, TestSolrDeletionPolicy1, TestMergePolicyConfig, FieldMutatingUpdateProcessorTest, DocumentBuilderTest, TestLMDirichletSimilarityFactory, DocValuesTest, RegexBoostProcessorTest, TestInfoStreamLogging, UUIDFieldTest, PeerSyncTest, SOLR749Test, TestDistribDocBasedVersion, HdfsCollectionsAPIDistributedZkTest, ZkControllerTest, ParsingFieldUpdateProcessorsTest, TestFaceting, TestCollationFieldDocValues, WordBreakSolrSpellCheckerTest, CopyFieldTest, ScriptEngineTest, BasicFunctionalityTest, HdfsDirectoryTest, TermVectorComponentTest, TermsComponentTest, SolrCoreTest, TestLMJelinekMercerSimilarityFactory, QueryParsingTest, TestFieldTypeResource, IndexSchemaTest, BadComponentTest, TestLRUCache, BasicDistributedZkTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, SyncSliceTest, OverseerTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ZkSolrClientTest, SliceStateUpdateTest, TestDistributedSearch]
   [junit4] Completed on J0 in 14.73s, 1 test, 1 failure <<< FAILURES!

[...truncated 501 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:439: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1308: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:941: There were test failures: 358 suites, 1554 tests, 1 failure, 35 ignored (7 assumptions)

Total time: 46 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-fcs-b128 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure