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/09 14:30:38 UTC

[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 1312 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1312/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseParallelGC

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([B24F1E52FD92E561:33A9904A8ACD855D]: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.GeneratedMethodAccessor67.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 10305 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> 2245397 T8003 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /x_asc/s
   [junit4]   2> 2245403 T8003 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 2245406 T8003 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2245412 T8003 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51628
   [junit4]   2> 2245412 T8003 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2245413 T8003 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2245413 T8003 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr
   [junit4]   2> 2245414 T8003 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 2245462 T8003 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 2245557 T8003 oasc.CoreContainer.<init> New CoreContainer 1651546640
   [junit4]   2> 2245558 T8003 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 2245560 T8003 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2245561 T8003 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2245561 T8003 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2245562 T8003 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2245562 T8003 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2245562 T8003 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2245562 T8003 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2245562 T8003 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2245563 T8003 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2245573 T8003 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2245574 T8003 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2245574 T8003 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2245581 T8013 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 2245582 T8013 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 2245583 T8013 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 2245583 T8013 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 2245705 T8013 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2245839 T8013 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2245840 T8013 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2245857 T8013 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 2246664 T8013 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2246672 T8013 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2246677 T8013 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2246687 T8013 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2246694 T8013 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2246701 T8013 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2246702 T8013 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 2246702 T8013 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 2246703 T8013 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2246703 T8013 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 2246704 T8013 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 2246704 T8013 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2246705 T8013 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.TestDistributedSearch-1391951517841/control/data/
   [junit4]   2> 2246705 T8013 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@357c4ab7
   [junit4]   2> 2246706 T8013 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391951517841/control/data
   [junit4]   2> 2246706 T8013 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1391951517841/control/data/index/
   [junit4]   2> 2246707 T8013 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1391951517841/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 2246708 T8013 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391951517841/control/data/index
   [junit4]   2> 2246708 T8013 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2775643639325711]
   [junit4]   2> 2246709 T8013 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6c083daf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26281145)),segFN=segments_1,generation=1}
   [junit4]   2> 2246710 T8013 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2246711 T8013 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 2246715 T8013 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2246716 T8013 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2246716 T8013 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2246717 T8013 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2246717 T8013 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2246718 T8013 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2246718 T8013 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2246718 T8013 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2246719 T8013 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2246720 T8013 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2246720 T8013 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2246720 T8013 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2246721 T8013 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 2246722 T8013 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 2246722 T8013 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 2246723 T8013 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 2246724 T8013 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 2246725 T8013 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 2246725 T8013 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 2246726 T8013 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 2246727 T8013 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 2246727 T8013 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 2246728 T8013 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 2246728 T8013 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 2246729 T8013 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 2246730 T8013 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246730 T8013 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246731 T8013 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246732 T8013 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246733 T8013 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246733 T8013 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246734 T8013 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246735 T8013 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2246735 T8013 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 2246736 T8013 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 2246753 T8013 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2246756 T8013 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 2246763 T8013 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 2246764 T8013 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 2246764 T8013 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=83.0517578125, floorSegmentMB=0.4189453125, forceMergeDeletesPctAllowed=28.774481710451948, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 2246765 T8013 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6c083daf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26281145)),segFN=segments_1,generation=1}
   [junit4]   2> 2246766 T8013 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2246766 T8013 oass.SolrIndexSearcher.<init> Opening Searcher@2f60e8b5[collection1] main
   [junit4]   2> 2246767 T8013 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 2246777 T8013 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2246858 T8014 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f60e8b5[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 2246866 T8013 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 2246873 T8003 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
   [junit4]   2> 2246874 T8003 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 2246877 T8003 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2246885 T8003 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51629
   [junit4]   2> 2246886 T8003 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 2246887 T8003 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2246888 T8003 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr
   [junit4]   2> 2246889 T8003 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 2246941 T8003 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/solr.xml
   [junit4]   2> 2247067 T8003 oasc.CoreContainer.<init> New CoreContainer 157787719
   [junit4]   2> 2247068 T8003 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/]
   [junit4]   2> 2247070 T8003 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 2247070 T8003 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 2247070 T8003 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 2247071 T8003 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2247071 T8003 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 2247071 T8003 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2247072 T8003 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 2247072 T8003 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 2247072 T8003 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 2247084 T8003 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2247085 T8003 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2247085 T8003 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2247096 T8025 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1
   [junit4]   2> 2247096 T8025 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/'
   [junit4]   2> 2247098 T8025 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 2247098 T8025 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 2247211 T8025 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 2247282 T8025 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2247283 T8025 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 2247292 T8025 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 2248080 T8025 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2248089 T8025 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 2248094 T8025 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2248105 T8025 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2248113 T8025 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 2248117 T8025 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2248118 T8025 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 2248118 T8025 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 2248118 T8025 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2248119 T8025 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 2248120 T8025 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 2248120 T8025 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2248120 T8025 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data/
   [junit4]   2> 2248121 T8025 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@357c4ab7
   [junit4]   2> 2248122 T8025 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data
   [junit4]   2> 2248122 T8025 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data/index/
   [junit4]   2> 2248122 T8025 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1391951517841/shard0/data/index' doesn't exist. Creating new index...
   [junit4]   2> 2248123 T8025 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data/index
   [junit4]   2> 2248124 T8025 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2775643639325711]
   [junit4]   2> 2248125 T8025 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@2cf9de4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a091569)),segFN=segments_1,generation=1}
   [junit4]   2> 2248125 T8025 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2248126 T8025 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 2248132 T8025 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2248133 T8025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2248133 T8025 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2248133 T8025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2248134 T8025 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2248134 T8025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2248134 T8025 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2248134 T8025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2248135 T8025 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2248135 T8025 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2248136 T8025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2248136 T8025 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2248136 T8025 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 2248137 T8025 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 2248137 T8025 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 2248138 T8025 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 2248139 T8025 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 2248139 T8025 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 2248140 T8025 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 2248140 T8025 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 2248141 T8025 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 2248141 T8025 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 2248142 T8025 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 2248142 T8025 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 2248142 T8025 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 2248143 T8025 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248144 T8025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248145 T8025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248145 T8025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248146 T8025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248146 T8025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248148 T8025 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248149 T8025 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2248150 T8025 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 2248151 T8025 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 2248165 T8025 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2248167 T8025 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 2248174 T8025 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 2248175 T8025 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 2248176 T8025 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=83.0517578125, floorSegmentMB=0.4189453125, forceMergeDeletesPctAllowed=28.774481710451948, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 2248177 T8025 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@2cf9de4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a091569)),segFN=segments_1,generation=1}
   [junit4]   2> 2248177 T8025 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 2248178 T8025 oass.SolrIndexSearcher.<init> Opening Searcher@2fe4163c[collection1] main
   [junit4]   2> 2248178 T8025 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 2248187 T8025 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2248255 T8026 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2fe4163c[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 2248267 T8025 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 2248269 T8003 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
   [junit4]   2> 2248270 T8003 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C2151 name=collection1 org.apache.solr.core.SolrCore@652b7a4
   [junit4]   2> 2248579 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1459566958099824640)} 0 2
   [junit4]   2> ASYNC  NEW_CORE C2152 name=collection1 org.apache.solr.core.SolrCore@3da74f91
   [junit4]   2> 2248587 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1459566958108213248)} 0 2
   [junit4]   2> 2248595 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[1 (1459566958115553280)]} 0 3
   [junit4]   2> 2248601 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[1 (1459566958122893312)]} 0 2
   [junit4]   2> 2248609 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[2 (1459566958130233344)]} 0 3
   [junit4]   2> 2248616 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[2 (1459566958138621952)]} 0 2
   [junit4]   2> 2248623 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[3 (1459566958145961984)]} 0 2
   [junit4]   2> 2248630 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[3 (1459566958153302016)]} 0 2
   [junit4]   2> 2248636 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[4 (1459566958159593472)]} 0 2
   [junit4]   2> 2248646 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[4 (1459566958171127808)]} 0 1
   [junit4]   2> 2248655 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[5 (1459566958178467840)]} 0 3
   [junit4]   2> 2248667 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[5 (1459566958192099328)]} 0 2
   [junit4]   2> 2248672 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[6 (1459566958198390784)]} 0 1
   [junit4]   2> 2248680 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[6 (1459566958204682240)]} 0 3
   [junit4]   2> 2248686 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[7 (1459566958212022272)]} 0 2
   [junit4]   2> 2248692 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[7 (1459566958218313728)]} 0 2
   [junit4]   2> 2248698 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[8 (1459566958225653760)]} 0 0
   [junit4]   2> 2248705 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[8 (1459566958232993792)]} 0 1
   [junit4]   2> 2248712 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[9 (1459566958239285248)]} 0 2
   [junit4]   2> 2248717 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[9 (1459566958245576704)]} 0 1
   [junit4]   2> 2248721 T8007 C2151 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2248730 T8007 C2151 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6c083daf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26281145)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6c083daf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26281145)),segFN=segments_2,generation=2}
   [junit4]   2> 2248733 T8007 C2151 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 2248736 T8007 C2151 oass.SolrIndexSearcher.<init> Opening Searcher@443cb716[collection1] main
   [junit4]   2> 2248737 T8007 C2151 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 2248739 T8014 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@443cb716[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c9)}
   [junit4]   2> 2248740 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={commit=true&wt=javabin&version=2&waitSearcher=true&softCommit=false} {commit=} 0 19
   [junit4]   2> 2248744 T8022 C2152 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2248752 T8022 C2152 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@2cf9de4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a091569)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@2cf9de4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a091569)),segFN=segments_2,generation=2}
   [junit4]   2> 2248753 T8022 C2152 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 2248755 T8022 C2152 oass.SolrIndexSearcher.<init> Opening Searcher@4dd98442[collection1] main
   [junit4]   2> 2248756 T8022 C2152 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 2248758 T8026 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4dd98442[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c9)}
   [junit4]   2> 2248759 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={commit=true&wt=javabin&version=2&waitSearcher=true&softCommit=false} {commit=} 0 15
   [junit4]   2> 2248766 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[10 (1459566958294859776)]} 0 3
   [junit4]   2> 2248772 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[10 (1459566958301151232)]} 0 3
   [junit4]   2> 2248777 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[11 (1459566958308491264)]} 0 1
   [junit4]   2> 2248784 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[11 (1459566958314782720)]} 0 2
   [junit4]   2> 2248788 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[12 (1459566958321074176)]} 0 1
   [junit4]   2> 2248792 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[12 (1459566958324219904)]} 0 1
   [junit4]   2> 2248802 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[13 (1459566958333657088)]} 0 2
   [junit4]   2> 2248809 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[13 (1459566958340997120)]} 0 3
   [junit4]   2> 2248814 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[1001 (1459566958346240000)]} 0 2
   [junit4]   2> 2248819 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[1001 (1459566958351482880)]} 0 2
   [junit4]   2> 2248825 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[14 (1459566958358822912)]} 0 1
   [junit4]   2> 2248830 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[14 (1459566958364065792)]} 0 1
   [junit4]   2> 2248835 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[15 (1459566958369308672)]} 0 1
   [junit4]   2> 2248840 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[15 (1459566958373502976)]} 0 2
   [junit4]   2> 2248845 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[16 (1459566958379794432)]} 0 2
   [junit4]   2> 2248849 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[16 (1459566958383988736)]} 0 1
   [junit4]   2> 2248856 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[17 (1459566958389231616)]} 0 3
   [junit4]   2> 2248862 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[17 (1459566958396571648)]} 0 3
   [junit4]   2> 2248871 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[100 (1459566958407057408)]} 0 1
   [junit4]   2> 2248876 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[100 (1459566958412300288)]} 0 1
   [junit4]   2> 2248883 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[101 (1459566958419640320)]} 0 1
   [junit4]   2> 2248887 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[101 (1459566958423834624)]} 0 1
   [junit4]   2> 2248893 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[102 (1459566958430126080)]} 0 1
   [junit4]   2> 2248900 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[102 (1459566958437466112)]} 0 1
   [junit4]   2> 2248906 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[103 (1459566958443757568)]} 0 1
   [junit4]   2> 2248912 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[103 (1459566958449000448)]} 0 2
   [junit4]   2> 2248917 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[104 (1459566958455291904)]} 0 1
   [junit4]   2> 2248923 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[104 (1459566958461583360)]} 0 1
   [junit4]   2> 2248928 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[105 (1459566958466826240)]} 0 1
   [junit4]   2> 2248933 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[105 (1459566958472069120)]} 0 1
   [junit4]   2> 2248942 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[106 (1459566958480457728)]} 0 2
   [junit4]   2> 2248949 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[106 (1459566958488846336)]} 0 1
   [junit4]   2> 2248955 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[107 (1459566958495137792)]} 0 1
   [junit4]   2> 2248961 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[107 (1459566958501429248)]} 0 2
   [junit4]   2> 2248969 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[108 (1459566958509817856)]} 0 1
   [junit4]   2> 2248974 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[108 (1459566958515060736)]} 0 1
   [junit4]   2> 2248980 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[109 (1459566958520303616)]} 0 2
   [junit4]   2> 2248988 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[109 (1459566958528692224)]} 0 2
   [junit4]   2> 2248992 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[110 (1459566958533935104)]} 0 1
   [junit4]   2> 2249001 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[110 (1459566958543372288)]} 0 1
   [junit4]   2> 2249006 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[111 (1459566958548615168)]} 0 1
   [junit4]   2> 2249016 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[111 (1459566958559100928)]} 0 1
   [junit4]   2> 2249022 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[112 (1459566958565392384)]} 0 1
   [junit4]   2> 2249027 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[112 (1459566958570635264)]} 0 1
   [junit4]   2> 2249032 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[113 (1459566958575878144)]} 0 1
   [junit4]   2> 2249041 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[113 (1459566958584266752)]} 0 2
   [junit4]   2> 2249046 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[114 (1459566958590558208)]} 0 1
   [junit4]   2> 2249053 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[114 (1459566958597898240)]} 0 1
   [junit4]   2> 2249058 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[115 (1459566958603141120)]} 0 1
   [junit4]   2> 2249064 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[115 (1459566958608384000)]} 0 2
   [junit4]   2> 2249069 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[116 (1459566958614675456)]} 0 1
   [junit4]   2> 2249074 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[116 (1459566958619918336)]} 0 2
   [junit4]   2> 2249079 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[117 (1459566958625161216)]} 0 2
   [junit4]   2> 2249084 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[117 (1459566958630404096)]} 0 2
   [junit4]   2> 2249090 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[118 (1459566958636695552)]} 0 1
   [junit4]   2> 2249098 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[118 (1459566958644035584)]} 0 2
   [junit4]   2> 2249104 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[119 (1459566958651375616)]} 0 2
   [junit4]   2> 2249109 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[119 (1459566958655569920)]} 0 2
   [junit4]   2> 2249115 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[120 (1459566958661861376)]} 0 2
   [junit4]   2> 2249130 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[120 (1459566958678638592)]} 0 2
   [junit4]   2> 2249136 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[121 (1459566958684930048)]} 0 1
   [junit4]   2> 2249142 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[121 (1459566958690172928)]} 0 2
   [junit4]   2> 2249148 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[122 (1459566958697512960)]} 0 1
   [junit4]   2> 2249153 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[122 (1459566958702755840)]} 0 1
   [junit4]   2> 2249159 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[123 (1459566958709047296)]} 0 2
   [junit4]   2> 2249166 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[123 (1459566958715338752)]} 0 2
   [junit4]   2> 2249171 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[124 (1459566958721630208)]} 0 1
   [junit4]   2> 2249176 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[124 (1459566958726873088)]} 0 1
   [junit4]   2> 2249181 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[125 (1459566958732115968)]} 0 2
   [junit4]   2> 2249186 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[125 (1459566958737358848)]} 0 1
   [junit4]   2> 2249190 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[126 (1459566958742601728)]} 0 1
   [junit4]   2> 2249198 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[126 (1459566958748893184)]} 0 2
   [junit4]   2> 2249203 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[127 (1459566958756233216)]} 0 0
   [junit4]   2> 2249214 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[127 (1459566958766718976)]} 0 1
   [junit4]   2> 2249229 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[128 (1459566958782447616)]} 0 2
   [junit4]   2> 2249235 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[128 (1459566958788739072)]} 0 1
   [junit4]   2> 2249245 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[129 (1459566958800273408)]} 0 0
   [junit4]   2> 2249252 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[129 (1459566958805516288)]} 0 2
   [junit4]   2> 2249258 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[130 (1459566958811807744)]} 0 2
   [junit4]   2> 2249263 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[130 (1459566958818099200)]} 0 1
   [junit4]   2> 2249269 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[131 (1459566958823342080)]} 0 2
   [junit4]   2> 2249275 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[131 (1459566958829633536)]} 0 2
   [junit4]   2> 2249280 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[132 (1459566958835924992)]} 0 1
   [junit4]   2> 2249285 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[132 (1459566958841167872)]} 0 1
   [junit4]   2> 2249291 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[133 (1459566958846410752)]} 0 2
   [junit4]   2> 2249296 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[133 (1459566958852702208)]} 0 1
   [junit4]   2> 2249302 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[134 (1459566958857945088)]} 0 2
   [junit4]   2> 2249306 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[134 (1459566958864236544)]} 0 0
   [junit4]   2> 2249316 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[135 (1459566958872625152)]} 0 2
   [junit4]   2> 2249324 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[135 (1459566958882062336)]} 0 1
   [junit4]   2> 2249330 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[136 (1459566958888353792)]} 0 1
   [junit4]   2> 2249336 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[136 (1459566958893596672)]} 0 2
   [junit4]   2> 2249341 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[137 (1459566958899888128)]} 0 1
   [junit4]   2> 2249346 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[137 (1459566958905131008)]} 0 1
   [junit4]   2> 2249352 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[138 (1459566958911422464)]} 0 1
   [junit4]   2> 2249357 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[138 (1459566958916665344)]} 0 1
   [junit4]   2> 2249363 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[139 (1459566958922956800)]} 0 2
   [junit4]   2> 2249368 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[139 (1459566958928199680)]} 0 1
   [junit4]   2> 2249374 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[140 (1459566958934491136)]} 0 1
   [junit4]   2> 2249379 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[140 (1459566958939734016)]} 0 1
   [junit4]   2> 2249384 T8011 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[141 (1459566958944976896)]} 0 1
   [junit4]   2> 2249390 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[141 (1459566958951268352)]} 0 1
   [junit4]   2> 2249395 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[142 (1459566958956511232)]} 0 1
   [junit4]   2> 2249399 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[142 (1459566958960705536)]} 0 1
   [junit4]   2> 2249405 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[143 (1459566958966996992)]} 0 1
   [junit4]   2> 2249410 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[143 (1459566958972239872)]} 0 1
   [junit4]   2> 2249415 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[144 (1459566958978531328)]} 0 1
   [junit4]   2> 2249420 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[144 (1459566958982725632)]} 0 1
   [junit4]   2> 2249425 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[145 (1459566958987968512)]} 0 1
   [junit4]   2> 2249430 T8019 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[145 (1459566958993211392)]} 0 1
   [junit4]   2> 2249434 T8010 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[146 (1459566958997405696)]} 0 1
   [junit4]   2> 2249438 T8021 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[146 (1459566959002648576)]} 0 1
   [junit4]   2> 2249442 T8007 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[147 (1459566959006842880)]} 0 0
   [junit4]   2> 2249451 T8020 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[147 (1459566959015231488)]} 0 1
   [junit4]   2> 2249460 T8012 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[148 (1459566959024668672)]} 0 1
   [junit4]   2> 2249465 T8022 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[148 (1459566959029911552)]} 0 1
   [junit4]   2> 2249469 T8008 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[149 (1459566959035154432)]} 0 0
   [junit4]   2> 2249475 T8018 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={wt=javabin&version=2} {add=[149 (1459566959041445888)]} 0 0
   [junit4]   2> 2249479 T8009 C2151 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2249495 T8009 C2151 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6c083daf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26281145)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@6c083daf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26281145)),segFN=segments_3,generation=3}
   [junit4]   2> 2249497 T8009 C2151 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 2249500 T8009 C2151 oass.SolrIndexSearcher.<init> Opening Searcher@4b4b0c3f[collection1] main
   [junit4]   2> 2249501 T8009 C2151 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 2249505 T8014 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4b4b0c3f[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):c9 _1(5.0):c59)}
   [junit4]   2> 2249506 T8009 C2151 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={commit=true&wt=javabin&version=2&waitSearcher=true&softCommit=false} {commit=} 0 27
   [junit4]   2> 2249509 T8023 C2152 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2249525 T8023 C2152 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@2cf9de4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a091569)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@2cf9de4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a091569)),segFN=segments_3,generation=3}
   [junit4]   2> 2249526 T8023 C2152 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 2249530 T8023 C2152 oass.SolrIndexSearcher.<init> Opening Searcher@d9c91a2[collection1] main
   [junit4]   2> 2249531 T8023 C2152 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 2249533 T8026 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d9c91a2[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):c9 _1(5.0):c59)}
   [junit4]   2> 2249533 T8023 C2152 oasup.LogUpdateProcessor.finish [collection1] webapp=/x_asc/s path=/update params={commit=true&wt=javabin&version=2&waitSearcher=true&softCommit=false} {commit=} 0 24
   [junit4]   2> 2249539 T8011 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_ti1+desc} hits=68 status=0 QTime=2 
   [junit4]   2> 2249557 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_ti1+desc&NOW=1391951521982&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249564 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&ids=142,10,1001,123,14,4,119,12,122,144&version=2&q=*:*&distrib=false&NOW=1391951521982} status=0 QTime=1 
   [junit4]   2> 2249568 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&q=*:*&sort=n_ti1+desc} hits=68 status=0 QTime=24 
   [junit4]   2> 2249575 T8010 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_ti1+asc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249588 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::114]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_ti1+asc&NOW=1391951522020&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249593 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::114]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=141,16,117,107,8,111,146,112,149,104&version=2&q=*:*&distrib=false&NOW=1391951522020} status=0 QTime=0 
   [junit4]   2> 2249602 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::114]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort=n_ti1+asc} hits=68 status=0 QTime=20 
   [junit4]   2> 2249610 T8007 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_f1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249625 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_f1+desc&NOW=1391951522054&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249632 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&ids=1001,123,1,130,146,12,143,11,145,106&version=2&q=*:*&distrib=false&NOW=1391951522054} status=0 QTime=1 
   [junit4]   2> 2249635 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s&q=*:*&sort=n_f1+desc} hits=68 status=0 QTime=19 
   [junit4]   2> 2249639 T8012 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_f1+asc} hits=68 status=0 QTime=0 
   [junit4]   2> 2249656 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_f1+asc&NOW=1391951522087&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2249662 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=16,115,138,8,121,131,125,124,6,126&version=2&q=*:*&distrib=false&NOW=1391951522087} status=0 QTime=0 
   [junit4]   2> 2249671 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort=n_f1+asc} hits=68 status=0 QTime=22 
   [junit4]   2> 2249676 T8008 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tf1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249685 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_tf1+desc&NOW=1391951522118&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249692 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&ids=10,128,103,1,117,8,12,122,147,149&version=2&q=*:*&distrib=false&NOW=1391951522118} status=0 QTime=1 
   [junit4]   2> 2249696 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&q=*:*&sort=n_tf1+desc} hits=68 status=0 QTime=16 
   [junit4]   2> 2249701 T8009 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tf1+asc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249714 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_tf1+asc&NOW=1391951522146&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2249721 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&ids=17,115,118,121,108,131,104,101,120,133&version=2&q=*:*&distrib=false&NOW=1391951522146} status=0 QTime=1 
   [junit4]   2> 2249724 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&q=*:*&sort=n_tf1+asc} hits=68 status=0 QTime=16 
   [junit4]   2> 2249734 T8011 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_d1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249753 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_d1+desc&NOW=1391951522177&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249759 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&ids=116,114,130,8,134,131,147,149,126,136&version=2&q=*:*&distrib=false&NOW=1391951522177} status=0 QTime=1 
   [junit4]   2> 2249761 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s&q=*:*&sort=n_d1+desc} hits=68 status=0 QTime=22 
   [junit4]   2> 2249765 T8010 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_d1+asc} hits=68 status=0 QTime=0 
   [junit4]   2> 2249773 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_d1+asc&NOW=1391951522208&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2249784 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=128,118,123,137,139,146,3,9,6,7&version=2&q=*:*&distrib=false&NOW=1391951522208} status=0 QTime=1 
   [junit4]   2> 2249786 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort=n_d1+asc} hits=68 status=0 QTime=16 
   [junit4]   2> 2249796 T8007 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_td1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249810 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_td1+desc&NOW=1391951522242&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249821 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=140,109,17,107,138,15,146,145,6,133&version=2&q=*:*&distrib=false&NOW=1391951522242} status=0 QTime=1 
   [junit4]   2> 2249823 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort=n_td1+desc} hits=68 status=0 QTime=19 
   [junit4]   2> 2249828 T8012 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_td1+asc} hits=68 status=0 QTime=0 
   [junit4]   2> 2249840 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_td1+asc&NOW=1391951522273&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249846 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s&ids=16,100,4,131,148,112,143,101,106,120&version=2&q=*:*&distrib=false&NOW=1391951522273} status=0 QTime=0 
   [junit4]   2> 2249849 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s&q=*:*&sort=n_td1+asc} hits=68 status=0 QTime=14 
   [junit4]   2> 2249855 T8008 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_l1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249863 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_l1+desc&NOW=1391951522297&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249872 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=135,110,100,123,138,8,121,134,146,106&version=2&q=*:*&distrib=false&NOW=1391951522297} status=0 QTime=1 
   [junit4]   2> 2249874 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::114]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort=n_l1+desc} hits=68 status=0 QTime=15 
   [junit4]   2> 2249878 T8011 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_l1+asc} hits=68 status=0 QTime=0 
   [junit4]   2> 2249886 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_l1+asc&NOW=1391951522321&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2249892 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=109,1,102,130,127,5,147,145,149,136&version=2&q=*:*&distrib=false&NOW=1391951522321} status=0 QTime=2 
   [junit4]   2> 2249894 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort=n_l1+asc} hits=68 status=0 QTime=11 
   [junit4]   2> 2249900 T8010 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tl1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249908 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_tl1+desc&NOW=1391951522342&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249913 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&ids=10,109,127,130,107,12,148,13,136,7&version=2&q=*:*&distrib=false&NOW=1391951522342} status=0 QTime=1 
   [junit4]   2> 2249916 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&q=*:*&sort=n_tl1+desc} hits=68 status=0 QTime=12 
   [junit4]   2> 2249922 T8007 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tl1+asc} hits=68 status=0 QTime=0 
   [junit4]   2> 2249932 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_tl1+asc&NOW=1391951522367&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2249937 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&ids=139,137,119,122,143,149,106,120,126,144&version=2&q=*:*&distrib=false&NOW=1391951522367} status=0 QTime=1 
   [junit4]   2> 2249940 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s&q=*:*&sort=n_tl1+asc} hits=68 status=0 QTime=11 
   [junit4]   2> 2249946 T8012 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_dt1+desc} hits=68 status=0 QTime=2 
   [junit4]   2> 2249954 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_dt1+desc&NOW=1391951522388&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249960 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&ids=109,113,103,100,102,105,146,134,15,120&version=2&q=*:*&distrib=false&NOW=1391951522388} status=0 QTime=1 
   [junit4]   2> 2249962 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&q=*:*&sort=n_dt1+desc} hits=68 status=0 QTime=12 
   [junit4]   2> 2249966 T8009 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_dt1+asc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249973 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_dt1+asc&NOW=1391951522408&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2249978 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&ids=10,128,17,139,127,108,143,132,145,133&version=2&q=*:*&distrib=false&NOW=1391951522408} status=0 QTime=1 
   [junit4]   2> 2249981 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s&q=*:*&sort=n_dt1+asc} hits=68 status=0 QTime=11 
   [junit4]   2> 2249986 T8008 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tdt1+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2249993 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_tdt1+desc&NOW=1391951522427&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2249999 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&ids=141,111,119,12,122,148,13,104,6,9&version=2&q=*:*&distrib=false&NOW=1391951522427} status=0 QTime=1 
   [junit4]   2> 2250001 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s&q=*:*&sort=n_tdt1+desc} hits=68 status=0 QTime=12 
   [junit4]   2> 2250010 T8011 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tdt1+asc} hits=68 status=0 QTime=0 
   [junit4]   2> 2250023 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_tdt1+asc&NOW=1391951522453&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2250030 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s&ids=128,103,114,100,1,117,134,112,129,143&version=2&q=*:*&distrib=false&NOW=1391951522453} status=0 QTime=0 
   [junit4]   2> 2250033 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s&q=*:*&sort=n_tdt1+asc} hits=68 status=0 QTime=18 
   [junit4]   2> 2250038 T8010 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=a_si+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2250046 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=a_si+desc&NOW=1391951522480&fl=id&start=0} hits=68 status=0 QTime=0 
   [junit4]   2> 2250051 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s&ids=10,1,8,5,12,13,2,9,3,7&version=2&q=*:*&distrib=false&NOW=1391951522480} status=0 QTime=0 
   [junit4]   2> 2250054 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::083]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s&q=*:*&sort=a_si+desc} hits=68 status=0 QTime=12 
   [junit4]   2> 2250058 T8007 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort={!func}testfunc(add(a_si,5))+desc} hits=68 status=0 QTime=1 
   [junit4]   2> 2250066 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort={!func}testfunc(add(a_si,5))+desc&NOW=1391951522500&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2250071 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=10,1,8,5,12,13,2,9,3,7&version=2&q=*:*&distrib=false&NOW=1391951522500} status=0 QTime=1 
   [junit4]   2> 2250073 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::213]:33332/x_asc/s|[ff01::083]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort={!func}testfunc(add(a_si,5))+desc} hits=68 status=0 QTime=11 
   [junit4]   2> 2250078 T8012 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=a_si+asc} hits=68 status=0 QTime=0 
   [junit4]   2> 2250086 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=a_si+asc&NOW=1391951522520&fl=id&start=0} hits=68 status=0 QTime=1 
   [junit4]   2> 2250093 T8021 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s&ids=1,4,12,11,13,2,6,3,9,7&version=2&q=*:*&distrib=false&NOW=1391951522520} status=0 QTime=0 
   [junit4]   2> 2250096 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=127.0.0.1:51629/x_asc/s|[ff01::083]:33332/x_asc/s&q=*:*&sort=a_si+asc} hits=68 status=0 QTime=14 
   [junit4]   2> 2250100 T8009 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=a_si+desc&fl=*,score} hits=68 status=0 QTime=0 
   [junit4]   2> 2250109 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=a_si+desc&NOW=1391951522543&start=0&fl=id,score} hits=68 status=0 QTime=1 
   [junit4]   2> 2250115 T8018 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s&ids=10,1,8,5,12,13,2,9,3,7&version=2&q=*:*&distrib=false&NOW=1391951522543&fl=*,score} status=0 QTime=1 
   [junit4]   2> 2250120 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::083]:33332/x_asc/s|[ff01::213]:33332/x_asc/s|127.0.0.1:51629/x_asc/s|[ff01::114]:33332/x_asc/s&q=*:*&sort=a_si+desc&fl=*,score} hits=68 status=0 QTime=15 
   [junit4]   2> 2250124 T8008 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tl1+asc&fl=*,score} hits=68 status=0 QTime=1 
   [junit4]   2> 2250133 T8020 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&version=2&q=*:*&distrib=false&rows=10&sort=n_tl1+asc&NOW=1391951522568&start=0&fl=id,score} hits=68 status=0 QTime=0 
   [junit4]   2> 2250138 T8019 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&isShard=true&shard.url=[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&ids=139,137,119,122,143,149,106,120,126,144&version=2&q=*:*&distrib=false&NOW=1391951522568&fl=*,score} status=0 QTime=1 
   [junit4]   2> 2250140 T8022 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&shards=[ff01::213]:33332/x_asc/s|[ff01::114]:33332/x_asc/s|127.0.0.1:51629/x_asc/s&q=*:*&sort=n_tl1+asc&fl=*,score} hits=68 status=0 QTime=10 
   [junit4]   2> 2250144 T8011 C2151 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={wt=javabin&version=2&q=*:*&distrib=false&sort=n_tl1+desc} hits=68 status=0 QTime=0 
   [junit4]   2> 2250154 T8023 C2152 oasc.SolrCore.execute [collection1] webapp=/x_asc/s path=/select params={fsv=true&wt=javabin&isShard=true&shar

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

y.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data/index
   [junit4]   2> 2261756 T8003 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data;done=false>>]
   [junit4]   2> 2261757 T8003 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391951517841/shard0/data
   [junit4]   2> 2261758 T8003 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/x_asc/s,null}
   [junit4]   2> 2261826 T8003 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=586255370
   [junit4]   2> 2261832 T8003 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6f6a62d0
   [junit4]   2> 2261857 T8003 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=36,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=8888,transaction_logs_total_number=3}
   [junit4]   2> 2261858 T8003 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 2261858 T8003 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 2261859 T8003 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 2261862 T8003 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 2261863 T8003 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 2261863 T8003 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391951517841/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391951517841/shard1/data/index;done=false>>]
   [junit4]   2> 2261864 T8003 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391951517841/shard1/data/index
   [junit4]   2> 2261865 T8003 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391951517841/shard1/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391951517841/shard1/data;done=false>>]
   [junit4]   2> 2261865 T8003 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391951517841/shard1/data
   [junit4]   2> 2261872 T8003 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/x_asc/s,null}
   [junit4]   2> 2261977 T8003 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDistributedSearch -Dtests.method=testDistribSearch -Dtests.seed=B24F1E52FD92E561 -Dtests.slow=true -Dtests.locale=th_TH -Dtests.timezone=America/Argentina/Buenos_Aires -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 16.6s | TestDistributedSearch.testDistribSearch <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected the partialResults header to be null
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([B24F1E52FD92E561:33A9904A8ACD855D]: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> 2261993 T8003 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=th_TH, timezone=America/Argentina/Buenos_Aires
   [junit4]   2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_51 (64-bit)/cpus=2,threads=1,free=176981448,total=492306432
   [junit4]   2> NOTE: All tests run in this JVM: [TestModifyConfFiles, CursorMarkTest, TestQueryUtils, OverseerTest, TermsComponentTest, OpenCloseCoreStressTest, MoreLikeThisHandlerTest, SearchHandlerTest, TestRangeQuery, ClusterStateUpdateTest, DocValuesTest, CollectionsAPIDistributedZkTest, DocValuesMissingTest, TestSolrQueryParser, BadCopyFieldTest, DirectSolrConnectionTest, HardAutoCommitTest, RegexBoostProcessorTest, TestPartialUpdateDeduplication, TestRandomDVFaceting, TestInfoStreamLogging, TestCSVResponseWriter, TestCopyFieldCollectionResource, MigrateRouteKeyTest, TestIndexingPerformance, TestSchemaResource, InfoHandlerTest, TestDefaultSimilarityFactory, SimplePostToolTest, CachingDirectoryFactoryTest, TestAddFieldRealTimeGet, TestCursorMarkWithoutUniqueKey, TestFieldResource, TestAtomicUpdateErrorCases, CoreAdminCreateDiscoverTest, TestLMJelinekMercerSimilarityFactory, TestJmxMonitoredMap, DateMathParserTest, SpellCheckCollatorTest, CoreAdminHandlerTest, TestExtendedDismaxParser, PreAnalyzedFieldTest, PreAnalyzedUpdateProcessorTest, HdfsBasicDistributedZk2Test, TestRTGBase, BasicDistributedZk2Test, DistribCursorPagingTest, TriLevelCompositeIdRoutingTest, MBeansHandlerTest, TestUpdate, SliceStateTest, TestCoreContainer, PolyFieldTest, AnalysisErrorHandlingTest, LukeRequestHandlerTest, TestSchemaVersionResource, NoCacheHeaderTest, LeaderElectionTest, TestCodecSupport, TestMergePolicyConfig, TestCoreDiscovery, TestAnalyzedSuggestions, RangeFacetTest, RequestHandlersTest, SolrXmlInZkTest, CurrencyFieldXmlFileTest, CSVRequestHandlerTest, TestQuerySenderNoQuery, PluginInfoTest, TestDocBasedVersionConstraints, TestSerializedLuceneMatchVersion, SuggesterTest, UpdateRequestProcessorFactoryTest, SimpleFacetsTest, TestSolrXMLSerializer, TestOmitPositions, TestSweetSpotSimilarityFactory, IndexSchemaRuntimeFieldTest, TestSolrDeletionPolicy1, CursorPagingTest, HdfsChaosMonkeySafeLeaderTest, XsltUpdateRequestHandlerTest, CurrencyFieldOpenExchangeTest, TestUtils, ConvertedLegacyTest, TestJmxIntegration, ShardRoutingCustomTest, TestStressUserVersions, TestLazyCores, FileUtilsTest, CacheHeaderTest, TestFastOutputStream, TestDFRSimilarityFactory, TestFastWriter, TestCollationField, BlockCacheTest, DisMaxRequestHandlerTest, TestCSVLoader, TermVectorComponentTest, TestSolrJ, TestHashPartitioner, TestSolrXmlPersistence, SystemInfoHandlerTest, TestIndexSearcher, UniqFieldsUpdateProcessorFactoryTest, TestQueryTypes, TestSurroundQueryParser, TestFunctionQuery, TestJoin, TestLuceneMatchVersion, TestPhraseSuggestions, SolrCoreTest, TestReload, EchoParamsTest, DistributedSuggestComponentTest, TestSolrDeletionPolicy2, TestAnalyzeInfixSuggestions, URLClassifyProcessorTest, DocValuesMultiTest, AddSchemaFieldsUpdateProcessorFactoryTest, UnloadDistributedZkTest, CopyFieldTest, ZkCLITest, TestBinaryResponseWriter, TestFoldingMultitermQuery, UUIDFieldTest, TestReplicationHandler, TestSearchPerf, ZkNodePropsTest, AnalysisAfterCoreReloadTest, PathHierarchyTokenizerFactoryTest, ShowFileRequestHandlerTest, TestValueSourceCache, DistributedTermsComponentTest, NotRequiredUniqueKeyTest, TestFaceting, TestCollationFieldDocValues, TestBM25SimilarityFactory, TestShardHandlerFactory, DocumentAnalysisRequestHandlerTest, SOLR749Test, StatelessScriptUpdateProcessorFactoryTest, TestUniqueKeyFieldResource, TestSolrXmlPersistor, TestStressRecovery, TestRemoteStreaming, DistributedSpellCheckComponentTest, TestNRTOpen, OverseerCollectionProcessorTest, FullSolrCloudDistribCmdsTest, FileBasedSpellCheckerTest, TestIBSimilarityFactory, WordBreakSolrSpellCheckerTest, TestStressLucene, BasicZkTest, BadComponentTest, TestPseudoReturnFields, OpenExchangeRatesOrgProviderTest, ScriptEngineTest, NoFacetTest, TestDistributedGrouping, DistanceFunctionTest, AddBlockUpdateTest, TestReversedWildcardFilterFactory, QueryResultKeyTest, HighlighterTest, TestRecovery, DocumentBuilderTest, TestArbitraryIndexDir, MultiTermTest, FieldFacetExtrasTest, FieldAnalysisRequestHandlerTest, TestStressReorder, TestWordDelimiterFilterFactory, HdfsSyncSliceTest, DateFieldTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, SyncSliceTest, LeaderElectionIntegrationTest, ShardRoutingTest, RecoveryZkTest, TestRandomFaceting, ZkSolrClientTest, TestZkChroot, SliceStateUpdateTest, TestDistributedSearch]
   [junit4] Completed in 16.68s, 1 test, 1 failure <<< FAILURES!

[...truncated 540 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:453: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:433: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:491: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1268: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:901: There were test failures: 370 suites, 1599 tests, 1 failure, 40 ignored (6 assumptions)

Total time: 86 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure