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 2013/11/29 16:33:13 UTC

[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.7.0_45) - Build # 3442 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3442/
Java: 32bit/jdk1.7.0_45 -server -XX:+UseParallelGC

1 tests failed.
REGRESSION:  org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.testDistribSearch

Error Message:
this codec cannot write docvalues

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: this codec cannot write docvalues
	at __randomizedtesting.SeedInfo.seed([C81099AE3516A8A6:49F617B64249C89A]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:491)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:197)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
	at org.apache.solr.BaseDistributedSearchTestCase.commit(BaseDistributedSearchTestCase.java:496)
	at org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest.doTest(DistributedQueryComponentCustomSortTest.java:63)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:843)
	at sun.reflect.GeneratedMethodAccessor43.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 10979 lines...]
   [junit4] Suite: org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest
   [junit4]   2> 3392649 T10033 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /nqu/k
   [junit4]   2> 3392650 T10033 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020
   [junit4]   2> 3392654 T10033 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 3392656 T10033 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 3392658 T10033 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 3392748 T10033 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 3392821 T10033 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 3392821 T10033 oass.IndexSchema.readSchema Reading Solr Schema from schema-custom-field.xml
   [junit4]   2> 3392829 T10033 oass.IndexSchema.readSchema [null] Schema name=test-custom-field-sort
   [junit4]   2> 3392866 T10033 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 3392869 T10033 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3392870 T10033 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 3392870 T10033 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 3392870 T10033 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 3392885 T10033 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 3392885 T10033 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 3392885 T10033 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 3392963 T10033 oasc.CoreContainer.<init> New CoreContainer 24701383
   [junit4]   2> 3392963 T10033 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
   [junit4]   2> 3392965 T10033 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 3392965 T10033 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 3392966 T10033 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 3392966 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 3392966 T10033 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 3392966 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 3392967 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 3392967 T10033 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 3392967 T10033 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 3392977 T10033 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 3392977 T10033 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 3392977 T10033 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 3392983 T10034 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
   [junit4]   2> 3392983 T10034 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 3392983 T10034 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 3392983 T10034 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 3393050 T10034 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 3393132 T10034 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 3393133 T10034 oass.IndexSchema.readSchema Reading Solr Schema from schema-custom-field.xml
   [junit4]   2> 3393139 T10034 oass.IndexSchema.readSchema [collection1] Schema name=test-custom-field-sort
   [junit4]   2> 3393172 T10034 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 3393176 T10034 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3393177 T10034 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 3393177 T10034 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\
   [junit4]   2> 3393178 T10034 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ec595
   [junit4]   2> 3393178 T10034 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\
   [junit4]   2> 3393178 T10034 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\index/
   [junit4]   2> 3393178 T10034 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\index' doesn't exist. Creating new index...
   [junit4]   2> 3393178 T10034 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\index
   [junit4]   2> 3393179 T10034 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 3393180 T10034 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1d4a1de lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a7b37d)),segFN=segments_1,generation=1}
   [junit4]   2> 3393180 T10034 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3393180 T10034 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 3393183 T10034 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 3393184 T10034 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 3393184 T10034 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3393184 T10034 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3393184 T10034 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3393184 T10034 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3393184 T10034 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3393184 T10034 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 3393185 T10034 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 3393185 T10034 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3393185 T10034 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 3393186 T10034 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3393186 T10034 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3393186 T10034 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 3393187 T10034 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 3393187 T10034 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 3393187 T10034 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 3393188 T10034 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 3393188 T10034 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 3393189 T10034 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 3393189 T10034 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 3393190 T10034 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 3393190 T10034 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 3393190 T10034 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 3393191 T10034 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 3393191 T10034 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 3393191 T10034 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393191 T10034 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393193 T10034 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393193 T10034 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393193 T10034 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393194 T10034 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393194 T10034 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393195 T10034 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393195 T10034 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 3393196 T10034 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 3393204 T10034 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 3393207 T10034 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 3393211 T10034 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 3393211 T10034 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 3393211 T10034 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=50.6640625, floorSegmentMB=1.0888671875, forceMergeDeletesPctAllowed=28.390352486479202, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7906407559386093
   [junit4]   2> 3393212 T10034 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1d4a1de lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a7b37d)),segFN=segments_1,generation=1}
   [junit4]   2> 3393212 T10034 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3393212 T10034 oass.SolrIndexSearcher.<init> Opening Searcher@a365d0 main
   [junit4]   2> 3393213 T10034 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 3393222 T10034 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 3393265 T10035 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a365d0 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 3393269 T10034 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 3393279 T10033 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 3393284 T10033 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 3393287 T10033 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 3393292 T10033 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63641
   [junit4]   2> 3393292 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 3393292 T10033 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 3393292 T10033 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 3393292 T10033 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 3393318 T10033 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
   [junit4]   2> 3393391 T10033 oasc.CoreContainer.<init> New CoreContainer 17622693
   [junit4]   2> 3393392 T10033 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
   [junit4]   2> 3393394 T10033 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 3393395 T10033 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 3393396 T10033 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 3393397 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 3393397 T10033 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 3393398 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 3393399 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 3393399 T10033 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 3393400 T10033 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 3393411 T10033 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 3393411 T10033 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 3393412 T10033 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 3393418 T10046 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
   [junit4]   2> 3393418 T10046 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 3393420 T10046 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 3393421 T10046 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 3393483 T10046 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 3393555 T10046 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 3393555 T10046 oass.IndexSchema.readSchema Reading Solr Schema from schema-custom-field.xml
   [junit4]   2> 3393562 T10046 oass.IndexSchema.readSchema [collection1] Schema name=test-custom-field-sort
   [junit4]   2> 3393597 T10046 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 3393600 T10046 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3393601 T10046 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 3393601 T10046 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/control/data\
   [junit4]   2> 3393601 T10046 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ec595
   [junit4]   2> 3393602 T10046 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/control/data\
   [junit4]   2> 3393602 T10046 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/control/data\index/
   [junit4]   2> 3393602 T10046 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652\control\data\index' doesn't exist. Creating new index...
   [junit4]   2> 3393603 T10046 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/control/data\index
   [junit4]   2> 3393604 T10046 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 3393604 T10046 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@106556c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cf45)),segFN=segments_1,generation=1}
   [junit4]   2> 3393604 T10046 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3393605 T10046 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 3393608 T10046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 3393608 T10046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 3393608 T10046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3393609 T10046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3393609 T10046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3393609 T10046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3393609 T10046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3393610 T10046 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 3393610 T10046 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 3393610 T10046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3393611 T10046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 3393611 T10046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3393612 T10046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3393612 T10046 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 3393612 T10046 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 3393612 T10046 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 3393613 T10046 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 3393613 T10046 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 3393614 T10046 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 3393614 T10046 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 3393615 T10046 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 3393615 T10046 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 3393616 T10046 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 3393616 T10046 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 3393616 T10046 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 3393617 T10046 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 3393618 T10046 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393618 T10046 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393619 T10046 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393620 T10046 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393620 T10046 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393620 T10046 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393620 T10046 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393621 T10046 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3393621 T10046 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 3393623 T10046 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 3393623 T10046 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 3393626 T10046 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 3393632 T10046 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 3393632 T10046 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 3393633 T10046 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=50.6640625, floorSegmentMB=1.0888671875, forceMergeDeletesPctAllowed=28.390352486479202, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7906407559386093
   [junit4]   2> 3393633 T10046 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@106556c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cf45)),segFN=segments_1,generation=1}
   [junit4]   2> 3393633 T10046 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3393634 T10046 oass.SolrIndexSearcher.<init> Opening Searcher@162cb27 main
   [junit4]   2> 3393635 T10046 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 3393644 T10046 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 3393688 T10047 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@162cb27 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 3393694 T10046 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 3393695 T10033 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 3393696 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 3393702 T10033 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 3393709 T10033 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63644
   [junit4]   2> 3393710 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 3393710 T10033 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 3393711 T10033 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 3393711 T10033 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 3393746 T10033 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
   [junit4]   2> 3393829 T10033 oasc.CoreContainer.<init> New CoreContainer 7629541
   [junit4]   2> 3393829 T10033 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
   [junit4]   2> 3393832 T10033 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 3393832 T10033 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 3393832 T10033 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 3393832 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 3393832 T10033 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 3393832 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 3393834 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 3393834 T10033 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 3393834 T10033 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 3393839 T10033 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 3393841 T10033 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 3393841 T10033 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 3393851 T10058 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
   [junit4]   2> 3393851 T10058 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 3393854 T10058 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 3393855 T10058 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 3393919 T10058 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 3393992 T10058 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 3393993 T10058 oass.IndexSchema.readSchema Reading Solr Schema from schema-custom-field.xml
   [junit4]   2> 3393998 T10058 oass.IndexSchema.readSchema [collection1] Schema name=test-custom-field-sort
   [junit4]   2> 3394042 T10058 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 3394044 T10058 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3394048 T10058 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 3394048 T10058 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard0/data\
   [junit4]   2> 3394048 T10058 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ec595
   [junit4]   2> 3394048 T10058 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard0/data\
   [junit4]   2> 3394048 T10058 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard0/data\index/
   [junit4]   2> 3394048 T10058 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652\shard0\data\index' doesn't exist. Creating new index...
   [junit4]   2> 3394050 T10058 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard0/data\index
   [junit4]   2> 3394051 T10058 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 3394052 T10058 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@82a53c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b87aa)),segFN=segments_1,generation=1}
   [junit4]   2> 3394052 T10058 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3394053 T10058 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 3394056 T10058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 3394057 T10058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 3394057 T10058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3394058 T10058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3394058 T10058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3394058 T10058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3394058 T10058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3394059 T10058 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 3394059 T10058 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 3394059 T10058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3394059 T10058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 3394060 T10058 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3394060 T10058 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3394060 T10058 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 3394060 T10058 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 3394060 T10058 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 3394062 T10058 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 3394062 T10058 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 3394062 T10058 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 3394062 T10058 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 3394063 T10058 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 3394063 T10058 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 3394064 T10058 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 3394064 T10058 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 3394064 T10058 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 3394066 T10058 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 3394066 T10058 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394067 T10058 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394067 T10058 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394068 T10058 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394068 T10058 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394069 T10058 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394069 T10058 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394070 T10058 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394070 T10058 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 3394071 T10058 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 3394078 T10058 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 3394080 T10058 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 3394087 T10058 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 3394088 T10058 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 3394088 T10058 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=50.6640625, floorSegmentMB=1.0888671875, forceMergeDeletesPctAllowed=28.390352486479202, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7906407559386093
   [junit4]   2> 3394089 T10058 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@82a53c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b87aa)),segFN=segments_1,generation=1}
   [junit4]   2> 3394089 T10058 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3394089 T10058 oass.SolrIndexSearcher.<init> Opening Searcher@12a3d20 main
   [junit4]   2> 3394090 T10058 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 3394101 T10058 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 3394149 T10059 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12a3d20 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 3394155 T10058 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 3394156 T10033 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 3394156 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 3394166 T10033 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 3394172 T10033 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63647
   [junit4]   2> 3394175 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 3394175 T10033 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 3394175 T10033 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 3394175 T10033 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 3394202 T10033 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
   [junit4]   2> 3394272 T10033 oasc.CoreContainer.<init> New CoreContainer 24600667
   [junit4]   2> 3394272 T10033 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 3394275 T10033 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 3394281 T10033 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 3394281 T10033 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 3394281 T10033 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 3394288 T10070 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
   [junit4]   2> 3394288 T10070 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 3394291 T10070 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 3394294 T10070 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 3394357 T10070 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 3394441 T10070 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 3394441 T10070 oass.IndexSchema.readSchema Reading Solr Schema from schema-custom-field.xml
   [junit4]   2> 3394448 T10070 oass.IndexSchema.readSchema [collection1] Schema name=test-custom-field-sort
   [junit4]   2> 3394482 T10070 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 3394484 T10070 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3394486 T10070 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 3394486 T10070 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard1/data\
   [junit4]   2> 3394486 T10070 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ec595
   [junit4]   2> 3394487 T10070 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard1/data\
   [junit4]   2> 3394487 T10070 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard1/data\index/
   [junit4]   2> 3394487 T10070 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652\shard1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 3394487 T10070 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard1/data\index
   [junit4]   2> 3394488 T10070 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 3394489 T10070 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@ac48b5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1749d39)),segFN=segments_1,generation=1}
   [junit4]   2> 3394489 T10070 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3394490 T10070 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 3394493 T10070 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 3394493 T10070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 3394493 T10070 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3394494 T10070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3394494 T10070 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3394494 T10070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3394494 T10070 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3394495 T10070 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 3394495 T10070 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 3394496 T10070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3394496 T10070 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 3394496 T10070 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3394497 T10070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3394497 T10070 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 3394497 T10070 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 3394497 T10070 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 3394497 T10070 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 3394498 T10070 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 3394498 T10070 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 3394500 T10070 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 3394500 T10070 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 3394501 T10070 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 3394501 T10070 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 3394501 T10070 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 3394501 T10070 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 3394502 T10070 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 3394502 T10070 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394504 T10070 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394504 T10070 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394504 T10070 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394504 T10070 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394505 T10070 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394505 T10070 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394506 T10070 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394506 T10070 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 3394507 T10070 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 3394513 T10070 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 3394515 T10070 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 3394520 T10070 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 3394520 T10070 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 3394521 T10070 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=50.6640625, floorSegmentMB=1.0888671875, forceMergeDeletesPctAllowed=28.390352486479202, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7906407559386093
   [junit4]   2> 3394522 T10070 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@ac48b5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1749d39)),segFN=segments_1,generation=1}
   [junit4]   2> 3394522 T10070 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3394522 T10070 oass.SolrIndexSearcher.<init> Opening Searcher@38664a main
   [junit4]   2> 3394523 T10070 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 3394533 T10070 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 3394577 T10071 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38664a main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 3394583 T10070 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 3394584 T10033 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 3394584 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 3394593 T10033 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 3394597 T10033 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63650
   [junit4]   2> 3394598 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 3394599 T10033 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 3394599 T10033 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 3394599 T10033 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 3394635 T10033 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
   [junit4]   2> 3394707 T10033 oasc.CoreContainer.<init> New CoreContainer 25969257
   [junit4]   2> 3394707 T10033 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
   [junit4]   2> 3394709 T10033 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 3394709 T10033 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 3394709 T10033 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 3394709 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 3394709 T10033 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 3394709 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 3394711 T10033 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 3394711 T10033 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 3394711 T10033 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 3394717 T10033 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 3394717 T10033 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 3394717 T10033 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 3394725 T10082 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
   [junit4]   2> 3394725 T10082 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 3394731 T10082 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 3394732 T10082 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 3394817 T10082 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 3394892 T10082 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 3394892 T10082 oass.IndexSchema.readSchema Reading Solr Schema from schema-custom-field.xml
   [junit4]   2> 3394899 T10082 oass.IndexSchema.readSchema [collection1] Schema name=test-custom-field-sort
   [junit4]   2> 3394936 T10082 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 3394939 T10082 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 3394940 T10082 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 3394941 T10082 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard2/data\
   [junit4]   2> 3394941 T10082 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ec595
   [junit4]   2> 3394941 T10082 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard2/data\
   [junit4]   2> 3394941 T10082 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard2/data\index/
   [junit4]   2> 3394941 T10082 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652\shard2\data\index' doesn't exist. Creating new index...
   [junit4]   2> 3394942 T10082 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.handler.component.DistributedQueryComponentCustomSortTest-1385739074652/shard2/data\index
   [junit4]   2> 3394943 T10082 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 3394944 T10082 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@aef76e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c51116)),segFN=segments_1,generation=1}
   [junit4]   2> 3394944 T10082 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3394944 T10082 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 3394947 T10082 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 3394948 T10082 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 3394948 T10082 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3394948 T10082 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 3394948 T10082 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3394949 T10082 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 3394949 T10082 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3394949 T10082 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 3394949 T10082 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 3394950 T10082 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 3394950 T10082 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 3394951 T10082 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3394952 T10082 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 3394952 T10082 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 3394952 T10082 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 3394952 T10082 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 3394953 T10082 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 3394953 T10082 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 3394954 T10082 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 3394954 T10082 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 3394955 T10082 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 3394955 T10082 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 3394956 T10082 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 3394956 T10082 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 3394956 T10082 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 3394957 T10082 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 3394957 T10082 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394958 T10082 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394958 T10082 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394959 T10082 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394959 T10082 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394960 T10082 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394960 T10082 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394961 T10082 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 3394961 T10082 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 3394962 T10082 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 3394968 T10082 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 3394971 T10082 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 3394976 T10082 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 3394976 T10082 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 3394977 T10082 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=50.6640625, floorSegmentMB=1.0888671875, forceMergeDeletesPctAllowed=28.390352486479202, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7906407559386093
   [junit4]   2> 3394978 T10082 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@aef76e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c51116)),segFN=segments_1,generation=1}
   [junit4]   2> 3394978 T10082 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 3394978 T10082 oass.SolrIndexSearcher.<init> Opening Searcher@fbad6a main
   [junit4]   2> 3394979 T10082 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 3394987 T10082 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 3395039 T10083 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fbad6a main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 3395043 T10082 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 3395045 T10033 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 3395045 T10033 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C2593 name=collection1 org.apache.solr.core.SolrCore@bfecd7
   [junit4]   2> 3395063 T10042 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1453052737804566528)} 0 2
   [junit4]   2> ASYNC  NEW_CORE C2594 name=collection1 org.apache.solr.core.SolrCore@15e6224
   [junit4]   2> 3395075 T10054 C2594 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1453052737817149440)} 0 2
   [junit4]   2> ASYNC  NEW_CORE C2595 name=collection1 org.apache.solr.core.SolrCore@b4f0f4
   [junit4]   2> 3395088 T10065 C2595 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1453052737831829504)} 0 2
   [junit4]   2> ASYNC  NEW_CORE C2596 name=collection1 org.apache.solr.core.SolrCore@1dbe1f6
   [junit4]   2> 3395100 T10077 C2596 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1453052737843363840)} 0 2
   [junit4]   2> 3395104 T10043 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[1 (1453052737848606720)]} 0 1
   [junit4]   2> 3395107 T10055 C2594 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[1 (1453052737852801024)]} 0 0
   [junit4]   2> 3395109 T10044 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[2 (1453052737854898176)]} 0 0
   [junit4]   2> 3395113 T10066 C2595 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[2 (1453052737858043904)]} 0 1
   [junit4]   2> 3395114 T10045 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[3 (1453052737860141056)]} 0 0
   [junit4]   2> 3395118 T10078 C2596 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[3 (1453052737864335360)]} 0 0
   [junit4]   2> 3395122 T10040 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[4 (1453052737868529664)]} 0 0
   [junit4]   2> 3395125 T10056 C2594 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[4 (1453052737871675392)]} 0 0
   [junit4]   2> 3395127 T10042 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[5 (1453052737873772544)]} 0 0
   [junit4]   2> 3395131 T10067 C2595 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[5 (1453052737877966848)]} 0 0
   [junit4]   2> 3395134 T10043 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[6 (1453052737881112576)]} 0 0
   [junit4]   2> 3395136 T10079 C2596 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[6 (1453052737883209728)]} 0 0
   [junit4]   2> 3395140 T10044 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[7 (1453052737887404032)]} 0 0
   [junit4]   2> 3395142 T10057 C2594 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[7 (1453052737889501184)]} 0 0
   [junit4]   2> 3395145 T10045 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[8 (1453052737892646912)]} 0 0
   [junit4]   2> 3395147 T10068 C2595 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[8 (1453052737894744064)]} 0 0
   [junit4]   2> 3395151 T10041 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[9 (1453052737898938368)]} 0 0
   [junit4]   2> 3395154 T10080 C2596 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[9 (1453052737902084096)]} 0 0
   [junit4]   2> 3395157 T10040 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[10 (1453052737905229824)]} 0 0
   [junit4]   2> 3395160 T10052 C2594 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[10 (1453052737908375552)]} 0 0
   [junit4]   2> 3395163 T10042 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[11 (1453052737911521280)]} 0 0
   [junit4]   2> 3395166 T10069 C2595 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[11 (1453052737914667008)]} 0 0
   [junit4]   2> 3395169 T10043 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[12 (1453052737917812736)]} 0 0
   [junit4]   2> 3395172 T10081 C2596 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[12 (1453052737920958464)]} 0 0
   [junit4]   2> 3395175 T10044 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[13 (1453052737924104192)]} 0 0
   [junit4]   2> 3395179 T10053 C2594 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={version=2&wt=javabin} {add=[13 (1453052737928298496)]} 0 0
   [junit4]   2> 3395181 T10045 C2593 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3395187 T10045 C2593 oasup.LogUpdateProcessor.finish [collection1] webapp=/nqu/k path=/update params={waitSearcher=true&version=2&commit=true&softCommit=false&wt=javabin} {} 0 6
   [junit4]   2> 3395187 T10045 C2593 oasc.SolrException.log ERROR java.lang.UnsupportedOperationException: this codec cannot write docvalues
   [junit4]   2> 		at org.apache.lucene.codecs.lucene3x.Lucene3xCodec$1.fieldsConsumer(Lucene3xCodec.java:73)
   [junit4]   2> 		at org.apache.lucene.index.DocValuesProcessor.flush(DocValuesProcessor.java:77)
   [junit4]   2> 		at org.apache.lucene.index.TwoStoredFieldsConsumers.flush(TwoStoredFieldsConsumers.java:42)
   [junit4]   2> 		at org.apache.lucene.index.DocFieldProcessor.flush(DocFieldProcessor.java:80)
   [junit4]   2> 		at org.apache.lucene.index.DocumentsWriterPerThread.flush(DocumentsWriterPerThread.java:465)
   [junit4]   2> 		at org.apache.lucene.index.DocumentsWriter.doFlush(DocumentsWriter.java:506)
   [junit4]   2> 		at org.apache.lucene.index.DocumentsWriter.flushAllThreads(DocumentsWriter.java:616)
   [junit4]   2> 		at org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:2815)
   [junit4]   2> 		at org.apache.lucene.index.IndexWriter.commitInternal(IndexWriter.java:2970)
   [junit4]   2> 		at org.apache.lucene.index.IndexWriter.commit(IndexWriter.java:2940)
   [junit4]   2> 		at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:559)
   [junit4]   2> 		at org.apache.solr.update.processor.RunUpdateProcessor.processCommit(RunUpdateProcessorFactory.java:95)
   [junit4]   2> 		at org.apache.solr.update.processor.UpdateRequestProcessor.processCommit(UpdateRequestProcessor.java:64)
   [junit4]   2> 		at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalCommit(DistributedUpdateProcessor.java:1444)
   [junit4]   2> 		at org.apache.solr.update.processor.DistributedUpdateProcessor.processCommit(DistributedUpdateProcessor.java:1421)
   [junit4]   2> 		at org.apache.solr.update.processor.LogUpdateProcessor.processCommit(LogUpdateProcessorFactory.java:157)
   [junit4]   2> 		at org.apache.solr.handler.RequestHandlerUtils.handleCommit(RequestHandlerUtils.java:69)
   [junit4]   2> 		at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
   [junit4]   2> 		at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
   [junit4]   2> 		at org.apache.solr.core.SolrCore.execute(SolrCore.java:1872)
   [junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:753)
   [junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
   [junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:200)
   [junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
   [junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:134)
   [junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
   [junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
   [junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
   [junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
   [junit4]   2> 		at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
   [junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
   [junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
   [junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
   [junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
   [junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
   [junit4]   2> 		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
   [junit4]   2> 		at org.eclipse.jetty.server.Server.handle(Server.java:368)
   [junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
   [junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
   [junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
   [junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:861)
   [junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
   [junit4]   2> 		at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
   [junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
   [junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
   [junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
   [junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 3395188 T10045 C2593 oasc.SolrException.log ERROR null:java.lang.UnsupportedOperationException: this codec cannot write docvalues
   [junit4]   2> 		at org.apache.lucene.codecs.lucene3x.Lucene3xCodec$1.fieldsConsumer(Lucene3xCodec.java:73)
   [junit4]   2> 		at org.apache.lucene.index.DocValuesProcessor.flush(DocValuesProcessor.java:77)
   [junit4]   2> 		at org.apache.lucene.index.TwoStoredFieldsConsumers.flush(TwoStoredFieldsConsumers.java:42)
   [junit4]   2> 		at org.apache.lucene.index.DocFieldProcessor.flush(DocFieldProcessor.java:80)
   [junit4]   2> 		at org.apache.lucene.index.DocumentsWriterPerThread.flush(DocumentsWriterPerThread.java:465)
   [junit4]   2> 		at org.apache.lucene.index.DocumentsWriter.doFlush(DocumentsWriter.java:506)
   [junit4]   2> 		at org.apache.lucene.index.DocumentsWriter.flushAllThreads(DocumentsWriter.java:616)
   [junit4]   2> 		at org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:2815)
   [junit4]   2> 		at org.apache.lucene.index.IndexWriter.commitInternal(IndexWriter.java:2970)
   [junit4]   2> 		at org.apache.lucene.index.IndexWriter.commit(IndexWriter.java:2940)
   [junit4]   2> 		at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:559)
   [junit4]   2> 		at org.apache.solr.update.processor.RunUpdateProcessor.processCommit(RunUpdateProcessorFactory.java:95)
   [junit4]   2> 		at org.apache.solr.update.processor.UpdateRequestProcessor.processCommit(UpdateRequestProcessor.java:64)
   [junit4]   2> 		at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalCommit(DistributedUpdateProcessor.java:1444)
   [junit4]   2> 		at org.apache.solr.update.processor.DistributedUpdateProcessor.processCommit(DistributedUpdateProcessor.java:1421)
   [junit4]   2> 		at org.apache.solr.update.processor.LogUpdateProcessor.processCommit(LogUpdateProcessorFactory.java:157)
   [junit4]   2> 		at org.apache.solr.handler.RequestHandlerUtils.handleCommit(RequestHandlerUtils.java:69)
   [junit4]   2> 		at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
   [junit4]   2> 		at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
   [junit4]   2> 		at org.apache.solr.core.SolrCore.execute(SolrCore.java:1872)
   [junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:753)
   [junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
   [junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:200)
   [junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
   [junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:134)
   [junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
   [junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
   [junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
   [junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.jav

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

ex;done=false>>]
   [junit4]   2> 3395712 T10033 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\index
   [junit4]   2> 3395712 T10033 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\;done=false>>]
   [junit4]   2> 3395712 T10033 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-DistributedQueryComponentCustomSortTest-1385739074020\
   [junit4]   2> NOTE: test params are: codec=Lucene3x, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=nl, timezone=Pacific/Enderbury
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_45 (32-bit)/cpus=2,threads=1,free=212129144,total=397934592
   [junit4]   2> NOTE: All tests run in this JVM: [TestDistributedGrouping, NoCacheHeaderTest, TestDefaultSearchFieldResource, TestWordDelimiterFilterFactory, SignatureUpdateProcessorFactoryTest, SuggesterTest, HdfsDirectoryTest, DocumentBuilderTest, TestGroupingSearch, TestSolrQueryParser, AliasIntegrationTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TestFuzzyAnalyzedSuggestions, TestJoin, OverseerCollectionProcessorTest, FileUtilsTest, TestUniqueKeyFieldResource, CoreAdminHandlerTest, FieldMutatingUpdateProcessorTest, SampleTest, TestShardHandlerFactory, HdfsCollectionsAPIDistributedZkTest, LegacyHTMLStripCharFilterTest, SpellCheckCollatorTest, TestCSVLoader, TestDocSet, ModifyConfFileTest, ZkControllerTest, SliceStateTest, MigrateRouteKeyTest, TestDocBasedVersionConstraints, DirectUpdateHandlerOptimizeTest, TestDynamicFieldCollectionResource, TestIndexSearcher, TestSchemaVersionResource, PreAnalyzedFieldTest, TestExtendedDismaxParser, SortByFunctionTest, SolrIndexSplitterTest, DistributedDebugComponentTest, CollectionsAPIDistributedZkTest, BasicDistributedZk2Test, TestFunctionQuery, PrimitiveFieldTypeTest, FastVectorHighlighterTest, TestRealTimeGet, JSONWriterTest, TestFastOutputStream, CacheHeaderTest, TestStressReorder, BasicDistributedZkTest, TestIBSimilarityFactory, ZkSolrClientTest, CSVRequestHandlerTest, TestNonNRTOpen, UUIDFieldTest, TestMaxScoreQueryParser, TestSolrQueryParserResource, TestStressUserVersions, AlternateDirectoryTest, TestAnalyzedSuggestions, TestFieldTypeCollectionResource, LeaderElectionIntegrationTest, TestSchemaResource, SystemInfoHandlerTest, TestAnalyzeInfixSuggestions, DocValuesMultiTest, SuggesterWFSTTest, DefaultValueUpdateProcessorTest, DistributedSuggesterComponentTest, TestCustomSort, TestTrie, TestCSVResponseWriter, TestSolrCoreProperties, RecoveryZkTest, EnumFieldTest, SolrInfoMBeanTest, OpenExchangeRatesOrgProviderTest, CopyFieldTest, TestSweetSpotSimilarityFactory, MBeansHandlerTest, TestSurroundQueryParser, FieldAnalysisRequestHandlerTest, TestHashPartitioner, SpellingQueryConverterTest, TestRandomFaceting, TriLevelCompositeIdRoutingTest, TestCollationKeyRangeQueries, TestSearchPerf, SpatialFilterTest, TestCharFilters, AnalysisAfterCoreReloadTest, SliceStateUpdateTest, BJQParserTest, ShardRoutingCustomTest, ChaosMonkeyNothingIsSafeTest, SpellPossibilityIteratorTest, TestFieldTypeResource, ClusterStateTest, BlockDirectoryTest, TestSolrDeletionPolicy1, TestNumberUtils, TestSerializedLuceneMatchVersion, AssignTest, DocValuesMissingTest, SolrCmdDistributorTest, SynonymTokenizerTest, TestSolrXmlPersistence, TermVectorComponentDistributedTest, ChangedSchemaMergeTest, TestDocumentBuilder, DebugComponentTest, ResponseLogComponentTest, PeerSyncTest, TestReversedWildcardFilterFactory, TestConfig, TestCloudManagedSchemaAddField, TestOmitPositions, HdfsBasicDistributedZkTest, TestRemoteStreaming, TestSolrXml, ConvertedLegacyTest, DateMathParserTest, UnloadDistributedZkTest, TestLFUCache, SolrTestCaseJ4Test, TestCopyFieldCollectionResource, DocValuesTest, SimplePostToolTest, InfoHandlerTest, TestAddFieldRealTimeGet, TestStressLucene, TestMergePolicyConfig, CachingDirectoryFactoryTest, ZkNodePropsTest, HdfsBasicDistributedZk2Test, DateFieldTest, TestNRTOpen, TestRandomMergePolicy, ParsingFieldUpdateProcessorsTest, WordBreakSolrSpellCheckerTest, ExternalFileFieldSortTest, TestFiltering, QueryEqualityTest, TestPHPSerializedResponseWriter, TestAtomicUpdateErrorCases, JsonLoaderTest, SoftAutoCommitTest, BadCopyFieldTest, TestManagedSchemaFieldResource, SchemaVersionSpecificBehaviorTest, TestStressRecovery, TestWriterPerf, TestQueryUtils, TestCoreContainer, TestLuceneMatchVersion, CustomCollectionTest, TestSchemaSimilarityResource, TestFieldCollectionResource, TestPostingsSolrHighlighter, SuggesterFSTTest, TestArbitraryIndexDir, RequiredFieldsTest, TestSort, TestMultiCoreConfBootstrap, DirectSolrSpellCheckerTest, TestHighFrequencyDictionaryFactory, ChaosMonkeySafeLeaderTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, ClusterStateUpdateTest, LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, AutoCommitTest, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TestReload, TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, SpellCheckComponentTest, QueryElevationComponentTest, BadIndexSchemaTest, BasicFunctionalityTest, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, HighlighterTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, TestCoreDiscovery, SolrRequestParserTest, TestFoldingMultitermQuery, SuggesterTSTTest, PolyFieldTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate, StatelessScriptUpdateProcessorFactoryTest, DistanceFunctionTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, TestIndexingPerformance, MoreLikeThisHandlerTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, TestSolrIndexConfig, TestQuerySenderNoQuery, SolrIndexConfigTest, BadComponentTest, TestSolrDeletionPolicy2, MultiTermTest, TestBinaryField, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestPhraseSuggestions, TestCodecSupport, TestXIncludeConfig, EchoParamsTest, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TimeZoneUtilsTest, ResourceLoaderTest, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, TestSolrXMLSerializer, TestSystemIdResolver, PrimUtilsTest, TestSuggestSpellingConverter, DOMUtilTest, RAMDirectoryFactoryTest, TestSolrJ, TestLRUCache, TestUtils, CircularListTest, TestRTGBase, TestDistributedMissingSort, DeleteInactiveReplicaTest, DeleteReplicaTest, DeleteShardTest, ShardSplitTest, SolrXmlInZkTest, TestDistribDocBasedVersion, TestModifyConfFiles, HdfsChaosMonkeySafeLeaderTest, HdfsRecoveryZkTest, HdfsSyncSliceTest, HdfsUnloadDistributedZkTest, StressHdfsTest, QueryResultKeyTest, TestImplicitCoreProperties, TestInfoStreamLogging, TestReloadAndDeleteDocs, TestSolrXmlPersistor, CoreAdminCreateDiscoverTest, CoreMergeIndexesAdminHandlerTest, DistributedQueryComponentCustomSortTest]
   [junit4] Completed in 3.11s, 1 test, 1 error <<< FAILURES!

[...truncated 66 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:426: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:406: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1279: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:912: There were test failures: 349 suites, 1516 tests, 1 error, 39 ignored (11 assumptions)

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