You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/08/14 12:12:15 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_20-ea-b23) -
Build # 4248 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4248/
Java: 32bit/jdk1.8.0_20-ea-b23 -server -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.handler.component.DistributedSpellCheckComponentTest.testDistribSearch
Error Message:
Control data did not return any suggestions.
Stack Trace:
junit.framework.AssertionFailedError: Control data did not return any suggestions.
at __randomizedtesting.SeedInfo.seed([7AF237F204D6AA24:FB14B9EA7389CA18]:0)
at junit.framework.Assert.fail(Assert.java:50)
at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.validateControlData(DistributedSpellCheckComponentTest.java:95)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:551)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:538)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:517)
at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.doTest(DistributedSpellCheckComponentTest.java:159)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871)
at sun.reflect.GeneratedMethodAccessor48.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11455 lines...]
[junit4] Suite: org.apache.solr.handler.component.DistributedSpellCheckComponentTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\init-core-data-001
[junit4] 2> 3921510 T10333 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 3921511 T10333 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /dserj/jz
[junit4] 2> 3921514 T10333 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 3921518 T10333 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3921532 T10333 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61139
[junit4] 2> 3921533 T10333 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3921533 T10333 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3921533 T10333 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr
[junit4] 2> 3921533 T10333 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\'
[junit4] 2> 3921560 T10333 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\solr.xml
[junit4] 2> 3921567 T10333 oasc.CoreContainer.<init> New CoreContainer 23617819
[junit4] 2> 3921567 T10333 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\]
[junit4] 2> 3921568 T10333 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3921568 T10333 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3921568 T10333 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3921568 T10333 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3921568 T10333 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3921569 T10333 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3921569 T10333 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3921569 T10333 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3921569 T10333 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3921569 T10333 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 3921570 T10333 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3921570 T10333 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3921570 T10333 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3921574 T10343 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 3921577 T10343 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 3921579 T10343 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 3921580 T10343 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 3921625 T10343 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 3921653 T10343 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3921654 T10343 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3921660 T10343 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3921775 T10343 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3921784 T10343 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3921786 T10343 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3921795 T10343 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3921798 T10343 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3921800 T10343 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3921801 T10343 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3921801 T10343 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3921802 T10343 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3921803 T10343 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3921803 T10343 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3921803 T10343 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 3921804 T10343 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 3921804 T10343 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001/control/data\
[junit4] 2> 3921804 T10343 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@174805e
[junit4] 2> 3921806 T10343 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data
[junit4] 2> 3921807 T10343 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001/control/data\index/
[junit4] 2> 3921809 T10343 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index' doesn't exist. Creating new index...
[junit4] 2> 3921809 T10343 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index
[junit4] 2> 3921810 T10343 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=11.076171875, floorSegmentMB=2.0595703125, forceMergeDeletesPctAllowed=11.22929123978114, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4027295425659517
[junit4] 2> 3921817 T10343 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e7ba44),segFN=segments_1,generation=1}
[junit4] 2> 3921818 T10343 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3921819 T10343 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 3921822 T10343 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3921822 T10343 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3921822 T10343 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 3921823 T10343 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 3921823 T10343 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3921823 T10343 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3921824 T10343 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 3921824 T10343 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 3921824 T10343 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3921825 T10343 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3921825 T10343 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3921825 T10343 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3921826 T10343 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3921826 T10343 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3921827 T10343 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3921827 T10343 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3921827 T10343 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3921828 T10343 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3921829 T10343 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3921829 T10343 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3921830 T10343 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 3921830 T10343 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 3921831 T10343 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3921831 T10343 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3921832 T10343 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 3921832 T10343 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 3921832 T10343 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 3921833 T10343 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 3921833 T10343 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3921834 T10343 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3921834 T10343 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921834 T10343 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921835 T10343 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921835 T10343 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921836 T10343 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921836 T10343 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921837 T10343 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921837 T10343 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3921838 T10343 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 3921838 T10343 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 3921846 T10343 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3921848 T10343 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3921850 T10343 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3921852 T10343 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3921853 T10343 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3921854 T10343 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 3921857 T10343 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3921857 T10343 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3921858 T10343 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=28, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1585906104055108]
[junit4] 2> 3921860 T10343 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e7ba44),segFN=segments_1,generation=1}
[junit4] 2> 3921860 T10343 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3921861 T10343 oass.SolrIndexSearcher.<init> Opening Searcher@152311c[collection1] main
[junit4] 2> 3921861 T10343 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 3921861 T10343 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 3921862 T10343 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 3921862 T10343 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 3921862 T10343 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3921863 T10343 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 3921875 T10343 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 3921933 T10344 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@152311c[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 3921937 T10343 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3921938 T10333 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3921938 T10333 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3921939 T10333 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3921946 T10333 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61142
[junit4] 2> 3921948 T10333 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3921948 T10333 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3921949 T10333 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr
[junit4] 2> 3921949 T10333 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\'
[junit4] 2> 3921975 T10333 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\solr.xml
[junit4] 2> 3921980 T10333 oasc.CoreContainer.<init> New CoreContainer 9852091
[junit4] 2> 3921981 T10333 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\]
[junit4] 2> 3921981 T10333 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 3921982 T10333 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 3921982 T10333 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3921983 T10333 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3921983 T10333 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3921984 T10333 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3921984 T10333 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3921985 T10333 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3921985 T10333 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3921986 T10333 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 3921986 T10333 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3921987 T10333 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3921987 T10333 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3921990 T10355 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 3921990 T10355 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 3921990 T10355 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 3921990 T10355 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 3922024 T10355 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 3922051 T10355 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3922052 T10355 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3922057 T10355 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3922134 T10355 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3922136 T10355 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3922136 T10355 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3922139 T10355 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3922141 T10355 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3922142 T10355 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3922142 T10355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3922142 T10355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3922142 T10355 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3922143 T10355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 3922143 T10355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 3922143 T10355 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 3922143 T10355 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 3922144 T10355 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001/shard0/data\
[junit4] 2> 3922144 T10355 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@174805e
[junit4] 2> 3922144 T10355 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard0\data
[junit4] 2> 3922145 T10355 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001/shard0/data\index/
[junit4] 2> 3922145 T10355 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard0\data\index' doesn't exist. Creating new index...
[junit4] 2> 3922145 T10355 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard0\data\index
[junit4] 2> 3922146 T10355 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=11.076171875, floorSegmentMB=2.0595703125, forceMergeDeletesPctAllowed=11.22929123978114, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4027295425659517
[junit4] 2> 3922152 T10355 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard0\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@375bfb),segFN=segments_1,generation=1}
[junit4] 2> 3922152 T10355 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3922153 T10355 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 3922156 T10355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3922156 T10355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3922156 T10355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 3922157 T10355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 3922157 T10355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3922157 T10355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3922157 T10355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 3922158 T10355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 3922158 T10355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3922158 T10355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3922159 T10355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3922159 T10355 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3922159 T10355 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3922160 T10355 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3922160 T10355 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3922161 T10355 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3922161 T10355 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3922161 T10355 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3922161 T10355 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3922162 T10355 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3922162 T10355 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 3922163 T10355 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 3922163 T10355 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3922164 T10355 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3922164 T10355 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 3922164 T10355 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 3922164 T10355 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 3922164 T10355 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 3922165 T10355 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3922165 T10355 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3922165 T10355 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922166 T10355 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922166 T10355 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922167 T10355 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922167 T10355 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922168 T10355 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922168 T10355 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922169 T10355 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 3922170 T10355 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 3922170 T10355 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 3922172 T10355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3922174 T10355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3922175 T10355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3922177 T10355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3922179 T10355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3922180 T10355 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 3922184 T10355 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3922184 T10355 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3922185 T10355 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=28, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1585906104055108]
[junit4] 2> 3922188 T10355 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard0\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@375bfb),segFN=segments_1,generation=1}
[junit4] 2> 3922188 T10355 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3922188 T10355 oass.SolrIndexSearcher.<init> Opening Searcher@f1eff1[collection1] main
[junit4] 2> 3922188 T10355 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 3922188 T10355 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 3922189 T10355 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 3922189 T10355 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 3922190 T10355 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 3922190 T10355 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 3922205 T10355 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 3922257 T10355 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3922258 T10333 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3922258 T10333 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3922261 T10356 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f1eff1[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> ASYNC NEW_CORE C2638 name=collection1 org.apache.solr.core.SolrCore@1906a08
[junit4] 2> 3922265 T10338 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1476404110171308032)} 0 4
[junit4] 2> ASYNC NEW_CORE C2639 name=collection1 org.apache.solr.core.SolrCore@15d16cd
[junit4] 2> 3922271 T10349 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1476404110178648064)} 0 1
[junit4] 2> 3922279 T10339 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[1 (1476404110183890944)]} 0 4
[junit4] 2> 3922286 T10350 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[1 (1476404110191230976)]} 0 3
[junit4] 2> 3922289 T10340 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[2 (1476404110197522432)]} 0 1
[junit4] 2> 3922292 T10351 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[2 (1476404110200668160)]} 0 1
[junit4] 2> 3922295 T10341 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[3 (1476404110203813888)]} 0 1
[junit4] 2> 3922299 T10352 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[3 (1476404110208008192)]} 0 1
[junit4] 2> 3922303 T10342 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[4 (1476404110212202496)]} 0 1
[junit4] 2> 3922307 T10353 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[4 (1476404110216396800)]} 0 1
[junit4] 2> 3922310 T10337 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[5 (1476404110219542528)]} 0 1
[junit4] 2> 3922313 T10354 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[5 (1476404110223736832)]} 0 0
[junit4] 2> 3922317 T10338 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[6 (1476404110226882560)]} 0 1
[junit4] 2> 3922320 T10349 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[6 (1476404110230028288)]} 0 1
[junit4] 2> 3922323 T10339 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[7 (1476404110234222592)]} 0 0
[junit4] 2> 3922326 T10350 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[7 (1476404110237368320)]} 0 0
[junit4] 2> 3922330 T10340 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[8 (1476404110240514048)]} 0 1
[junit4] 2> 3922333 T10351 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[8 (1476404110243659776)]} 0 1
[junit4] 2> 3922336 T10342 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[9 (1476404110246805504)]} 0 1
[junit4] 2> 3922339 T10352 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[9 (1476404110249951232)]} 0 1
[junit4] 2> 3922341 T10337 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[10 (1476404110253096960)]} 0 0
[junit4] 2> 3922344 T10353 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[10 (1476404110256242688)]} 0 0
[junit4] 2> 3922348 T10338 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[12 (1476404110259388416)]} 0 1
[junit4] 2> 3922351 T10354 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[12 (1476404110262534144)]} 0 1
[junit4] 2> 3922354 T10339 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[13 (1476404110265679872)]} 0 1
[junit4] 2> 3922356 T10350 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[13 (1476404110268825600)]} 0 0
[junit4] 2> 3922360 T10341 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[14 (1476404110273019904)]} 0 1
[junit4] 2> 3922362 T10351 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[14 (1476404110275117056)]} 0 0
[junit4] 2> 3922365 T10340 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[15 (1476404110278262784)]} 0 0
[junit4] 2> 3922368 T10352 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[15 (1476404110281408512)]} 0 0
[junit4] 2> 3922372 T10342 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[16 (1476404110284554240)]} 0 1
[junit4] 2> 3922375 T10353 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[16 (1476404110287699968)]} 0 1
[junit4] 2> 3922378 T10337 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[17 (1476404110291894272)]} 0 1
[junit4] 2> 3922382 T10349 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[17 (1476404110295040000)]} 0 1
[junit4] 2> 3922386 T10338 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[18 (1476404110299234304)]} 0 1
[junit4] 2> 3922390 T10354 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[18 (1476404110304477184)]} 0 1
[junit4] 2> 3922394 T10339 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[19 (1476404110307622912)]} 0 1
[junit4] 2> 3922397 T10350 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[19 (1476404110311817216)]} 0 0
[junit4] 2> 3922400 T10341 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[20 (1476404110314962944)]} 0 0
[junit4] 2> 3922404 T10351 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[20 (1476404110318108672)]} 0 1
[junit4] 2> 3922407 T10340 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[21 (1476404110322302976)]} 0 1
[junit4] 2> 3922410 T10352 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[21 (1476404110324400128)]} 0 1
[junit4] 2> 3922414 T10342 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[22 (1476404110328594432)]} 0 1
[junit4] 2> 3922417 T10353 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[22 (1476404110332788736)]} 0 1
[junit4] 2> 3922420 T10337 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[23 (1476404110335934464)]} 0 1
[junit4] 2> 3922423 T10349 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[23 (1476404110339080192)]} 0 1
[junit4] 2> 3922426 T10338 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[24 (1476404110342225920)]} 0 1
[junit4] 2> 3922429 T10354 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[24 (1476404110345371648)]} 0 0
[junit4] 2> 3922433 T10339 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[25 (1476404110349565952)]} 0 0
[junit4] 2> 3922436 T10350 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[25 (1476404110352711680)]} 0 0
[junit4] 2> 3922440 T10341 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[26 (1476404110355857408)]} 0 1
[junit4] 2> 3922443 T10351 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={wt=javabin&version=2} {add=[26 (1476404110360051712)]} 0 1
[junit4] 2> 3922447 T10340 C2638 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3922493 T10340 C2638 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e7ba44),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e7ba44),segFN=segments_2,generation=2}
[junit4] 2> 3922494 T10340 C2638 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 3922499 T10340 C2638 oass.SolrIndexSearcher.<init> Opening Searcher@6e9abd[collection1] main
[junit4] 2> 3922500 T10340 C2638 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3922501 T10344 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e9abd[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):c25))}
[junit4] 2> 3922502 T10340 C2638 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 55
[junit4] 2> 3922506 T10352 C2639 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3922550 T10352 C2639 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard0\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@375bfb),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard0\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@375bfb),segFN=segments_2,generation=2}
[junit4] 2> 3922551 T10352 C2639 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 3922555 T10352 C2639 oass.SolrIndexSearcher.<init> Opening Searcher@2eb522[collection1] main
[junit4] 2> 3922556 T10352 C2639 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 3922557 T10356 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2eb522[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):c25))}
[junit4] 2> 3922558 T10352 C2639 oasup.LogUpdateProcessor.finish [collection1] webapp=/dserj/jz path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 52
[junit4] 2> 3922606 T10342 C2638 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={q=*:*&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&spellcheck.build=true&shards.qt=spellCheckCompRH} hits=25 status=0 QTime=43
[junit4] 2> 3922694 T10349 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&fsv=true&shard.url=http://127.0.0.1:61142/dserj/jz&rows=10&version=2&spellcheck.build=true&spellcheck.extendedResults=false&shards.qt=spellCheckCompRH&q=*:*&spellcheck=true&NOW=1408008680853&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin} hits=25 status=0 QTime=29
[junit4] 2> 3922697 T10354 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&shard.url=http://127.0.0.1:61142/dserj/jz&version=2&spellcheck.build=true&spellcheck.extendedResults=false&shards.qt=spellCheckCompRH&q=*:*&spellcheck=false&NOW=1408008680853&spellcheck.onlyMorePopular=false&ids=1,2,3,4,5,6,7,8,9,10&isShard=true&spellcheck.count=1&wt=javabin} status=0 QTime=0
[junit4] 2> 3922698 T10353 C2639 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz null
[junit4] 2> 3922698 T10353 C2639 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz null
[junit4] 2> 3922698 T10353 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={q=*:*&shards=http://127.0.0.1:61142/dserj/jz&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&spellcheck.build=true&shards.qt=spellCheckCompRH} hits=25 status=0 QTime=89
[junit4] 2> 3922726 T10337 C2638 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={q=*:*&spellcheck.q=toyata&distrib=false&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&group.field=id&shards.qt=spellCheckCompRH&group=true} hits=25 status=0 QTime=25
[junit4] 2> 3922731 T10351 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz&rows=10&version=2&spellcheck.extendedResults=false&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=toyata&spellcheck=true&NOW=1408008680973&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin&group.field=id&group=true} status=0 QTime=1
[junit4] 2> 3922736 T10352 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz&rows=10&group.topgroups.id=1&group.topgroups.id=2&group.topgroups.id=3&group.topgroups.id=4&group.topgroups.id=5&group.topgroups.id=6&group.topgroups.id=7&group.topgroups.id=8&group.topgroups.id=9&group.topgroups.id=10&version=2&group.distributed.second=true&spellcheck.extendedResults=false&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=toyata&spellcheck=false&NOW=1408008680973&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=1&wt=javabin&group.field=id&group=true} status=0 QTime=2
[junit4] 2> 3922739 T10349 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt,id&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz&version=2&spellcheck.extendedResults=false&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=toyata&spellcheck=false&NOW=1408008680973&spellcheck.onlyMorePopular=false&ids=1,2,3,4,5,6,7,8,9,10&isShard=true&spellcheck.count=1&wt=javabin&group.field=id} status=0 QTime=0
[junit4] 2> 3922742 T10350 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz {suggestions={toyata={numFound=2,startOffset=0,endOffset=6,suggestion=[toyota, sonata]}}}
[junit4] 1> {suggestions={toyata={numFound=2,startOffset=0,endOffset=6,suggestion=[toyota, sonata]}}}
[junit4] 2> 3922742 T10350 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz null
[junit4] 2> 3922742 T10350 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz null
[junit4] 2> 3922743 T10350 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={q=*:*&shards=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz&spellcheck.q=toyata&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&group.field=id&shards.qt=spellCheckCompRH&group=true} status=0 QTime=14
[junit4] 2> 3922746 T10338 C2638 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={q=*:*&spellcheck.q=toyata&distrib=false&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&group.field=id&shards.qt=spellCheckCompRH&group=true&spellcheck.extendedResults=true} hits=25 status=0 QTime=1
[junit4] 2> 3922751 T10353 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz&rows=10&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=toyata&spellcheck=true&NOW=1408008680992&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin&group.field=id&group=true} status=0 QTime=1
[junit4] 2> 3922754 T10352 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz&rows=10&group.topgroups.id=1&group.topgroups.id=2&group.topgroups.id=3&group.topgroups.id=4&group.topgroups.id=5&group.topgroups.id=6&group.topgroups.id=7&group.topgroups.id=8&group.topgroups.id=9&group.topgroups.id=10&version=2&group.distributed.second=true&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=toyata&spellcheck=false&NOW=1408008680992&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=1&wt=javabin&group.field=id&group=true} status=0 QTime=0
[junit4] 2> 3922757 T10349 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt,id&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=toyata&spellcheck=false&NOW=1408008680992&spellcheck.onlyMorePopular=false&ids=1,2,3,4,5,6,7,8,9,10&isShard=true&spellcheck.count=1&wt=javabin&group.field=id} status=0 QTime=0
[junit4] 2> 3922758 T10354 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz {suggestions={toyata={numFound=2,startOffset=0,endOffset=6,origFreq=0,suggestion=[{word=toyota,freq=1}, {word=sonata,freq=1}]}},correctlySpelled=false}
[junit4] 1> {suggestions={toyata={numFound=2,startOffset=0,endOffset=6,origFreq=0,suggestion=[{word=toyota,freq=1}, {word=sonata,freq=1}]}},correctlySpelled=false}
[junit4] 2> 3922758 T10354 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz null
[junit4] 2> 3922758 T10354 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz null
[junit4] 2> 3922759 T10354 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={q=*:*&shards=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz&spellcheck.q=toyata&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&group.field=id&shards.qt=spellCheckCompRH&group=true&spellcheck.extendedResults=true} status=0 QTime=11
[junit4] 2> 3922762 T10341 C2638 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&spellcheck.q=bluo&spellcheck=true&spellcheck.count=4&wt=javabin&group.field=id&group=true} hits=25 status=0 QTime=1
[junit4] 2> 3922769 T10353 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz&rows=10&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=bluo&spellcheck=true&NOW=1408008681009&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin&group.field=id&group=true} status=0 QTime=1
[junit4] 2> 3922771 T10351 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz&rows=10&group.topgroups.id=1&group.topgroups.id=2&group.topgroups.id=3&group.topgroups.id=4&group.topgroups.id=5&group.topgroups.id=6&group.topgroups.id=7&group.topgroups.id=8&group.topgroups.id=9&group.topgroups.id=10&version=2&group.distributed.second=true&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=bluo&spellcheck=false&NOW=1408008681009&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=4&wt=javabin&group.field=id&group=true} status=0 QTime=0
[junit4] 2> 3922774 T10352 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt,id&shard.url=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=*:*&spellcheck.q=bluo&spellcheck=false&NOW=1408008681009&spellcheck.onlyMorePopular=false&ids=1,2,3,4,5,6,7,8,9,10&isShard=true&spellcheck.count=4&wt=javabin&group.field=id} status=0 QTime=0
[junit4] 2> 3922775 T10350 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz {suggestions={bluo={numFound=2,startOffset=0,endOffset=4,origFreq=0,suggestion=[{word=blue,freq=1}, {word=glue,freq=1}]}},correctlySpelled=false}
[junit4] 1> {suggestions={bluo={numFound=2,startOffset=0,endOffset=4,origFreq=0,suggestion=[{word=blue,freq=1}, {word=glue,freq=1}]}},correctlySpelled=false}
[junit4] 2> 3922775 T10350 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz null
[junit4] 2> 3922775 T10350 C2639 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz null
[junit4] 2> 3922776 T10350 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={qt=spellCheckCompRH&fl=id,lowerfilt&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&shards=[ff01::213]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz&spellcheck.q=bluo&spellcheck=true&spellcheck.count=4&wt=javabin&group.field=id&group=true} status=0 QTime=11
[junit4] 2> 3922780 T10340 C2638 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=The+quick+reb+fox+jumped+over+the+lazy+brown+dogs&spellcheck=true&spellcheck.count=4&wt=javabin&group.field=id&group=true&spellcheck.collate=true} hits=0 status=0 QTime=2
[junit4] 2> 3922788 T10354 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&start=0&shard.url=http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz&rows=10&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=The+quick+reb+fox+jumped+over+the+lazy+brown+dogs&spellcheck=true&NOW=1408008681029&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=1
[junit4] 2> 3922790 T10349 C2639 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz {suggestions={reb={numFound=1,startOffset=10,endOffset=13,origFreq=0,suggestion=[{word=red,freq=14}]},dogs={numFound=1,startOffset=45,endOffset=49,origFreq=0,suggestion=[{word=dogs.,freq=14}]}},correctlySpelled=false,collations={collation=The quick red fox jumped over the lazy brown dogs.}}
[junit4] 1> {suggestions={reb={numFound=1,startOffset=10,endOffset=13,origFreq=0,suggestion=[{word=red,freq=14}]},dogs={numFound=1,startOffset=45,endOffset=49,origFreq=0,suggestion=[{word=dogs.,freq=14}]}},correctlySpelled=false,collations={collation=The quick red fox jumped over the lazy brown dogs.}}
[junit4] 2> 3922791 T10349 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={qt=spellCheckCompRH&fl=id,lowerfilt&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=The+quick+reb+fox+jumped+over+the+lazy+brown+dogs&shards=http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz&spellcheck=true&spellcheck.count=4&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=6
[junit4] 2> 3922795 T10342 C2638 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=true&distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=10&spellcheck.maxCollationTries=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=2
[junit4] 2> 3922804 T10351 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=true&distrib=false&qt=spellCheckCompRH&fl=id,score&spellcheck.maxCollations=10&start=0&fsv=true&spellcheck.maxCollationTries=10&shard.url=http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz&rows=10&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&NOW=1408008681044&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=1
[junit4] 2> 3922805 T10353 C2639 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},reb={numFound=1,startOffset=19,endOffset=22,origFreq=0,suggestion=[{word=red,freq=14}]}},correctlySpelled=false,collations={collation={collationQuery=lowerfilt:(+quick +red),hits=1,misspellingsAndCorrections={quock=quick,reb=red},collationInternalRank=0},collation={collationQuery=lowerfilt:(+quote +red),hits=12,misspellingsAndCorrections={quock=quote,reb=red},collationInternalRank=1}}}
[junit4] 1> {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},reb={numFound=1,startOffset=19,endOffset=22,origFreq=0,suggestion=[{word=red,freq=14}]}},correctlySpelled=false,collations={collation={collationQuery=lowerfilt:(+quick +red),hits=1,misspellingsAndCorrections={quock=quick,reb=red},collationInternalRank=0},collation={collationQuery=lowerfilt:(+quote +red),hits=12,misspellingsAndCorrections={quock=quote,reb=red},collationInternalRank=1}}}
[junit4] 2> 3922806 T10353 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=10&spellcheck.maxCollationTries=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&shards=http://127.0.0.1:61142/dserj/jz|[ff01::083]:33332/dserj/jz&spellcheck=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=5
[junit4] 2> 3922810 T10337 C2638 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=10&spellcheck.maxCollationTries=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} hits=0 status=0 QTime=1
[junit4] 2> 3922816 T10350 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&spellcheck.maxCollations=10&start=0&spellcheck.maxCollationTries=10&shard.url=http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz|[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz&rows=10&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&NOW=1408008681057&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=1
[junit4] 2> 3922816 T10352 C2639 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz|[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},reb={numFound=1,startOffset=19,endOffset=22,origFreq=0,suggestion=[{word=red,freq=14}]}},correctlySpelled=false,collations={collation=lowerfilt:(+quick +red),collationInternalRank=0,collation=lowerfilt:(+quote +red),collationInternalRank=1}}
[junit4] 1> {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},reb={numFound=1,startOffset=19,endOffset=22,origFreq=0,suggestion=[{word=red,freq=14}]}},correctlySpelled=false,collations={collation=lowerfilt:(+quick +red),collationInternalRank=0,collation=lowerfilt:(+quote +red),collationInternalRank=1}}
[junit4] 2> 3922817 T10352 C2639 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=10&spellcheck.maxCollationTries=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&shards=http://127.0.0.1:61142/dserj/jz|[ff01::114]:33332/dserj/jz|[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=4
[junit4] 2> ASYNC NEW_CORE C2640 name=collection1 org.apache.solr.core.SolrCore@1906a08
[junit4] 2> 3922829 T10338 C2640 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=0&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=10
[junit4] 2> ASYNC NEW_CORE C2641 name=collection1 org.apache.solr.core.SolrCore@15d16cd
[junit4] 2> 3922835 T10349 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id,score&spellcheck.maxCollations=1&start=0&fsv=true&spellcheck.maxCollationTries=0&shard.url=http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|[ff01::114]:33332/dserj/jz&rows=10&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&NOW=1408008681076&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=1
[junit4] 2> 3922836 T10354 C2641 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|[ff01::114]:33332/dserj/jz {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},reb={numFound=1,startOffset=19,endOffset=22,origFreq=0,suggestion=[{word=red,freq=14}]}},correctlySpelled=false,collations={collation=lowerfilt:(+quick +red)}}
[junit4] 1> {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},reb={numFound=1,startOffset=19,endOffset=22,origFreq=0,suggestion=[{word=red,freq=14}]}},correctlySpelled=false,collations={collation=lowerfilt:(+quick +red)}}
[junit4] 2> 3922836 T10354 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=0&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&shards=http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|[ff01::114]:33332/dserj/jz&spellcheck=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=4
[junit4] 2> 3922847 T10339 C2640 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:("quick+red+fox")&spellcheck.maxResultsForSuggest=10&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} hits=1 status=0 QTime=8
[junit4] 2> 3922857 T10353 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&group.distributed.first=true&distrib=false&fl=id,score&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&shard.url=[ff01::114]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz&spellcheck.maxResultsForSuggest=10&wt=javabin&group.field=id&group=true&qt=spellCheckCompRH&start=0&rows=10&spellcheck.alternativeTermCount=5&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:("quick+red+fox")&spellcheck=true&NOW=1408008681097&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&spellcheck.collate=true} status=0 QTime=2
[junit4] 2> 3922860 T10350 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&fl=id,score&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&shard.url=[ff01::114]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz&spellcheck.maxResultsForSuggest=10&wt=javabin&group.field=id&group=true&qt=spellCheckCompRH&start=0&rows=10&group.topgroups.id=9&spellcheck.alternativeTermCount=5&version=2&group.distributed.second=true&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:("quick+red+fox")&spellcheck=false&NOW=1408008681097&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&spellcheck.collate=true} status=0 QTime=0
[junit4] 2> 3922863 T10352 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt,id&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&shard.url=[ff01::114]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz&spellcheck.alternativeTermCount=5&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:("quick+red+fox")&spellcheck.maxResultsForSuggest=10&spellcheck=false&NOW=1408008681097&spellcheck.onlyMorePopular=false&ids=9&isShard=true&spellcheck.count=10&wt=javabin&group.field=id&spellcheck.collate=true} status=0 QTime=0
[junit4] 2> 3922864 T10351 C2641 oashc.SpellCheckComponent.finishStage [ff01::114]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz {suggestions={quick={numFound=1,startOffset=12,endOffset=17,origFreq=1,suggestion=[{word=quicker,freq=1}]},red={numFound=1,startOffset=18,endOffset=21,origFreq=14,suggestion=[{word=rod,freq=1}]},fox={numFound=2,startOffset=22,endOffset=25,origFreq=14,suggestion=[{word=fix,freq=1}, {word=ford,freq=1}]}},correctlySpelled=false,collations={collation=lowerfilt:("quicker red fox"),collationInternalRank=1}}
[junit4] 1> {suggestions={quick={numFound=1,startOffset=12,endOffset=17,origFreq=1,suggestion=[{word=quicker,freq=1}]},red={numFound=1,startOffset=18,endOffset=21,origFreq=14,suggestion=[{word=rod,freq=1}]},fox={numFound=2,startOffset=22,endOffset=25,origFreq=14,suggestion=[{word=fix,freq=1}, {word=ford,freq=1}]}},correctlySpelled=false,collations={collation=lowerfilt:("quicker red fox"),collationInternalRank=1}}
[junit4] 2> 3922864 T10351 C2641 oashc.SpellCheckComponent.finishStage [ff01::114]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz null
[junit4] 2> 3922865 T10351 C2641 oashc.SpellCheckComponent.finishStage [ff01::114]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz null
[junit4] 2> 3922865 T10351 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:("quick+red+fox")&shards=[ff01::114]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz|[ff01::213]:33332/dserj/jz&spellcheck.maxResultsForSuggest=10&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=12
[junit4] 2> 3922870 T10341 C2640 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:("rod+fix")&spellcheck.maxResultsForSuggest=10&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} hits=1 status=0 QTime=2
[junit4] 2> 3922880 T10354 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&group.distributed.first=true&distrib=false&fl=id,score&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&shard.url=http://127.0.0.1:61142/dserj/jz&spellcheck.maxResultsForSuggest=10&wt=javabin&group.field=id&group=true&qt=spellCheckCompRH&start=0&rows=10&spellcheck.alternativeTermCount=5&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:("rod+fix")&spellcheck=true&NOW=1408008681118&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&spellcheck.collate=true} status=0 QTime=3
[junit4] 2> 3922886 T10353 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&fl=id,score&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&shard.url=http://127.0.0.1:61142/dserj/jz&spellcheck.maxResultsForSuggest=10&wt=javabin&group.field=id&group=true&qt=spellCheckCompRH&start=0&rows=10&group.topgroups.id=26&spellcheck.alternativeTermCount=5&version=2&group.distributed.second=true&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:("rod+fix")&spellcheck=false&NOW=1408008681118&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&spellcheck.collate=true} status=0 QTime=3
[junit4] 2> 3922889 T10350 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt,id&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&shard.url=http://127.0.0.1:61142/dserj/jz&spellcheck.alternativeTermCount=5&version=2&spellcheck.extendedResults=true&shards.qt=spellCheckCompRH&q=lowerfilt:("rod+fix")&spellcheck.maxResultsForSuggest=10&spellcheck=false&NOW=1408008681118&spellcheck.onlyMorePopular=false&ids=26&isShard=true&spellcheck.count=10&wt=javabin&group.field=id&spellcheck.collate=true} status=0 QTime=0
[junit4] 2> 3922890 T10349 C2641 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz {suggestions={rod={numFound=2,startOffset=12,endOffset=15,origFreq=1,suggestion=[{word=red,freq=14}, {word=ford,freq=1}]},fix={numFound=1,startOffset=16,endOffset=19,origFreq=1,suggestion=[{word=fox,freq=14}]}},correctlySpelled=false,collations={collation=lowerfilt:("red fox"),collationInternalRank=2}}
[junit4] 1> {suggestions={rod={numFound=2,startOffset=12,endOffset=15,origFreq=1,suggestion=[{word=red,freq=14}, {word=ford,freq=1}]},fix={numFound=1,startOffset=16,endOffset=19,origFreq=1,suggestion=[{word=fox,freq=14}]}},correctlySpelled=false,collations={collation=lowerfilt:("red fox"),collationInternalRank=2}}
[junit4] 2> 3922890 T10349 C2641 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz null
[junit4] 2> 3922890 T10349 C2641 oashc.SpellCheckComponent.finishStage http://127.0.0.1:61142/dserj/jz null
[junit4] 2> 3922891 T10349 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=false&qt=spellCheckCompRH&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:("rod+fix")&shards=http://127.0.0.1:61142/dserj/jz&spellcheck.maxResultsForSuggest=10&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=17
[junit4] 2> 3922896 T10340 C2640 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=true&distrib=false&qt=spellCheckWithWordbreak&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=0&version=2&shards.qt=spellCheckWithWordbreak&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Bredfox+%2Bjum+%2Bped)&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} hits=0 status=0 QTime=3
[junit4] 2> 3922909 T10351 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=true&group.distributed.first=true&distrib=false&spellcheck.dictionary=default&spellcheck.dictionary=wordbreak&qt=spellCheckWithWordbreak&fl=id,score&spellcheck.maxCollations=1&start=0&spellcheck.maxCollationTries=0&shard.url=[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|[ff01::114]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz&rows=10&version=2&shards.qt=spellCheckWithWordbreak&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Bredfox+%2Bjum+%2Bped)&spellcheck=true&NOW=1408008681143&isShard=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=7
[junit4] 2> 3922910 T10352 C2641 oashc.SpellCheckComponent.finishStage [ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|[ff01::114]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},redfox={numFound=1,startOffset=19,endOffset=25,origFreq=0,suggestion=[{word=red fox,freq=14}]},ped={numFound=1,startOffset=32,endOffset=35,origFreq=0,suggestion=[{word=red,freq=14}]},jum ped={numFound=1,startOffset=27,endOffset=35,origFreq=0,suggestion=[{word=jumped,freq=14}]}},correctlySpelled=false,collations={collation={collationQuery=lowerfilt:(+quick (+red +fox) +jum +red),hits=0,misspellingsAndCorrections={quock=quick,redfox=red fox,ped=red}}}}
[junit4] 1> {suggestions={quock={numFound=2,startOffset=12,endOffset=17,origFreq=0,suggestion=[{word=quick,freq=1}, {word=quote,freq=12}]},redfox={numFound=1,startOffset=19,endOffset=25,origFreq=0,suggestion=[{word=red fox,freq=14}]},ped={numFound=1,startOffset=32,endOffset=35,origFreq=0,suggestion=[{word=red,freq=14}]},jum ped={numFound=1,startOffset=27,endOffset=35,origFreq=0,suggestion=[{word=jumped,freq=14}]}},correctlySpelled=false,collations={collation={collationQuery=lowerfilt:(+quick (+red +fox) +jum +red),hits=0,misspellingsAndCorrections={quock=quick,redfox=red fox,ped=red}}}}
[junit4] 2> 3922911 T10352 C2641 oasc.SolrCore.execute [collection1] webapp=/dserj/jz path=/select params={spellcheck.collateExtendedResults=true&qt=spellCheckWithWordbreak&fl=id,lowerfilt&spellcheck.maxCollations=1&spellcheck.maxCollationTries=0&version=2&shards.qt=spellCheckWithWordbreak&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Bredfox+%2Bjum+%2Bped)&shards=[ff01::213]:33332/dserj/jz|[ff01::083]:33332/dserj/jz|[ff01::114]:33332/dserj/jz|http://127.0.0.1:61142/dserj/jz&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=12
[junit4] 2> 3922924 T10333 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=23617819
[junit4] 2> 3922925 T10333 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1906a08
[junit4] 2> 3922931 T10333 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=25,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=2290,transaction_logs_total_number=1}
[junit4] 2> 3922933 T10333 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 3922933 T10333 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 3922933 T10333 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 3922937 T10333 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 3922937 T10333 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 3922937 T10333 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index;done=false>>]
[junit4] 2> 3922937 T10333 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\control\data\index
[junit4] 2> 3922938 T10333 oasc.CachingDirectoryFactory.c
[...truncated too long message...]
CheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard1\data
[junit4] 2> 3407674 T10333 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard1\data\index;done=false>>]
[junit4] 2> 3407675 T10333 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001\tempDir-001\shard1\data\index
[junit4] 2> 3407676 T10333 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/dserj/jz,null}
[junit4] 2> 3413683 T10333 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedSpellCheckComponentTest -Dtests.method=testDistribSearch -Dtests.seed=7AF237F204D6AA24 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=Africa/Ceuta -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE -507.81s | DistributedSpellCheckComponentTest.testDistribSearch <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: Control data did not return any suggestions.
[junit4] > at __randomizedtesting.SeedInfo.seed([7AF237F204D6AA24:FB14B9EA7389CA18]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:50)
[junit4] > at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.validateControlData(DistributedSpellCheckComponentTest.java:95)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:551)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:538)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:517)
[junit4] > at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.doTest(DistributedSpellCheckComponentTest.java:159)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3413699 T10333 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.handler.component.DistributedSpellCheckComponentTest-7AF237F204D6AA24-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ro, timezone=Africa/Ceuta
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_20-ea (32-bit)/cpus=2,threads=1,free=89071192,total=331022336
[junit4] 2> NOTE: All tests run in this JVM: [SolrIndexConfigTest, PolyFieldTest, TestFieldResource, SampleTest, CSVRequestHandlerTest, TestSolr4Spatial, DistributedTermsComponentTest, AnalysisAfterCoreReloadTest, TestLMJelinekMercerSimilarityFactory, AddBlockUpdateTest, TestJmxIntegration, MultiTermTest, TestShardHandlerFactory, TestOmitPositions, TestSolrQueryParser, TestManagedSchema, SuggestComponentTest, DistanceFunctionTest, PreAnalyzedUpdateProcessorTest, TestRealTimeGet, StatsComponentTest, SystemInfoHandlerTest, IndexBasedSpellCheckerTest, DistributedExpandComponentTest, AbstractAnalyticsFacetTest, CircularListTest, TestFunctionQuery, TestCloudInspectUtil, TestImplicitCoreProperties, HdfsCollectionsAPIDistributedZkTest, TestReload, TestReRankQParserPlugin, AlternateDirectoryTest, ReturnFieldsTest, AnalyticsQueryTest, TestPHPSerializedResponseWriter, TestFaceting, TermsComponentTest, TestManagedStopFilterFactory, DirectUpdateHandlerOptimizeTest, TestTrie, TestMiniSolrCloudCluster, TestCollectionAPI, SuggesterFSTTest, ClusterStateTest, BlockDirectoryTest, TestComponentsName, TestSolr4Spatial2, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestCollapseQParserPlugin, TestSimpleQParserPlugin, SimplePostToolTest, SuggesterTest, UniqFieldsUpdateProcessorFactoryTest, TestOverriddenPrefixQueryForCustomFieldType, TestSort, TestAnalyzeInfixSuggestions, TestBlendedInfixSuggestions, DistributedQueryElevationComponentTest, TestCloudPivotFacet, ParsingFieldUpdateProcessorsTest, TestModifyConfFiles, TestFieldTypeResource, JSONWriterTest, TestNonNRTOpen, TestIBSimilarityFactory, AnalyticsMergeStrategyTest, TestComplexPhraseQParserPlugin, PreAnalyzedFieldTest, CustomCollectionTest, BinaryUpdateRequestHandlerTest, DirectSolrConnectionTest, ZkCLITest, HdfsBasicDistributedZkTest, RequestHandlersTest, TestSchemaNameResource, TestManagedSynonymFilterFactory, ReplicationFactorTest, TestWriterPerf, TestTolerantSearch, TestStressUserVersions, QueryElevationComponentTest, LeaderElectionIntegrationTest, TestFoldingMultitermQuery, StressHdfsTest, TestAtomicUpdateErrorCases, QueryResultKeyTest, TestConfigSets, ResourceLoaderTest, SOLR749Test, UUIDUpdateProcessorFallbackTest, MBeansHandlerTest, TestRecoveryHdfs, HttpPartitionTest, DistributedSuggestComponentTest, TestRemoteStreaming, UpdateRequestProcessorFactoryTest, SearchHandlerTest, TestMultiCoreConfBootstrap, TestUniqueKeyFieldResource, LeaderElectionTest, TestReplicationHandlerBackup, RankQueryTest, CoreMergeIndexesAdminHandlerTest, TestRequestStatusCollectionAPI, CoreAdminRequestStatusTest, DateMathParserTest, TestDefaultSearchFieldResource, ResponseLogComponentTest, ZkNodePropsTest, RAMDirectoryFactoryTest, XsltUpdateRequestHandlerTest, ConnectionManagerTest, EnumFieldTest, SpatialFilterTest, TestDFRSimilarityFactory, TestDistributedGrouping, TestDistributedSearch, TestInitQParser, SolrIndexSplitterTest, TestFieldCollectionResource, FieldMutatingUpdateProcessorTest, TestPseudoReturnFields, TestLazyCores, TestPhraseSuggestions, TestSurroundQueryParser, TestSystemIdResolver, HighlighterConfigTest, BasicFunctionalityTest, TriLevelCompositeIdRoutingTest, LegacyHTMLStripCharFilterTest, ChangedSchemaMergeTest, FileBasedSpellCheckerTest, SimpleFacetsTest, CoreContainerCoreInitFailuresTest, TestLuceneMatchVersion, SolrCmdDistributorTest, DocumentAnalysisRequestHandlerTest, TestJmxMonitoredMap, NotRequiredUniqueKeyTest, DistributedQueryComponentOptimizationTest, DistributedDebugComponentTest, QueryFacetTest, TestStandardQParsers, DebugComponentTest, TestCodecSupport, SpellCheckComponentTest, TestDynamicFieldResource, CoreAdminCreateDiscoverTest, TestExtendedDismaxParser, CacheHeaderTest, DateFieldTest, JsonLoaderTest, ShardSplitTest, TestMaxScoreQueryParser, LukeRequestHandlerTest, DistributedFacetPivotLongTailTest, StatelessScriptUpdateProcessorFactoryTest, BadCopyFieldTest, TestSolrIndexConfig, TestGroupingSearch, RegexBoostProcessorTest, CoreAdminHandlerTest, RemoteQueryErrorTest, FullSolrCloudDistribCmdsTest, HdfsRecoveryZkTest, InfoHandlerTest, TestDistribDocBasedVersion, NumericFieldsTest, TestPartialUpdateDeduplication, TestCustomSort, FieldFacetExtrasTest, FieldFacetTest, DocValuesTest, ShardRoutingTest, TestMergePolicyConfig, TestRandomDVFaceting, HdfsUnloadDistributedZkTest, ConvertedLegacyTest, TestRandomMergePolicy, TestCollationField, BasicDistributedZkTest, TestWordDelimiterFilterFactory, ShardRoutingCustomTest, DocValuesMultiTest, WordBreakSolrSpellCheckerTest, IndexSchemaRuntimeFieldTest, SuggesterWFSTTest, TestSchemaResource, SpellPossibilityIteratorTest, DistributedFacetPivotSmallTest, HighlighterTest, TestArbitraryIndexDir, TimeZoneUtilsTest, TestAnalyzedSuggestions, TestFieldSortValues, TestZkChroot, TestMissingGroups, ClusterStateUpdateTest, PeerSyncTest, TestPostingsSolrHighlighter, TestManagedResource, TestQuerySenderNoQuery, TestPerFieldSimilarity, ExternalFileFieldSortTest, DisMaxRequestHandlerTest, DeleteShardTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler, TestRandomFaceting, ZkSolrClientTest, AutoCommitTest, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest]
[junit4] Completed in -507.77s, 1 test, 1 failure <<< FAILURES!
[...truncated 624 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:474: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:454: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1307: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:922: There were test failures: 427 suites, 1771 tests, 1 failure, 44 ignored (23 assumptions)
Total time: 142 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_20-ea-b23 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_20-ea-b23) -
Build # 4249 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4249/
Java: 32bit/jdk1.8.0_20-ea-b23 -client -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.schema.TestCloudSchemaless
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.schema.TestCloudSchemaless: 1) Thread[id=15704, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestCloudSchemaless] at java.lang.Object.wait(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:146)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.schema.TestCloudSchemaless:
1) Thread[id=15704, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestCloudSchemaless]
at java.lang.Object.wait(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:146)
at __randomizedtesting.SeedInfo.seed([ECB8BAC1D8901F81]:0)
Build Log:
[...truncated 12144 lines...]
[junit4] Suite: org.apache.solr.schema.TestCloudSchemaless
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\init-core-data-001
[junit4] 2> 6308939 T15701 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 6308940 T15701 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 6308951 T15701 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 6308953 T15701 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 6308955 T15702 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 6309056 T15701 oasc.ZkTestServer.run start zk server on port:55277
[junit4] 2> 6309061 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6309072 T15708 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b4ff24 name:ZooKeeperConnection Watcher:127.0.0.1:55277 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6309072 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6309073 T15701 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 6309083 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6309085 T15710 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a73faf name:ZooKeeperConnection Watcher:127.0.0.1:55277/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6309086 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6309086 T15701 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 6309093 T15701 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 6309100 T15701 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 6309110 T15701 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 6309115 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-schemaless.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 6309116 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 6309124 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema-add-schema-fields-update-processor.xml to /configs/conf1/schema.xml
[junit4] 2> 6309124 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 6309130 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 6309130 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 6309136 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 6309136 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 6309141 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 6309141 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 6309146 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 6309146 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 6309150 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 6309150 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 6309155 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 6309155 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 6309160 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 6309160 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 6309167 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 6309167 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 6309172 T15701 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 6309172 T15701 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 6311793 T15701 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6311800 T15701 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55284
[junit4] 2> 6311802 T15701 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6311802 T15701 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6311802 T15701 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002
[junit4] 2> 6311803 T15701 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\'
[junit4] 2> 6311837 T15701 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\solr.xml
[junit4] 2> 6311851 T15701 oasc.CoreContainer.<init> New CoreContainer 5372335
[junit4] 2> 6311851 T15701 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\]
[junit4] 2> 6311852 T15701 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6311852 T15701 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6311852 T15701 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6311852 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6311853 T15701 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6311853 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6311853 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6311853 T15701 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6311854 T15701 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6311854 T15701 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 6311854 T15701 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6311854 T15701 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6311854 T15701 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6311854 T15701 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55277/solr
[junit4] 2> 6311854 T15701 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6311857 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6311859 T15721 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19e229a name:ZooKeeperConnection Watcher:127.0.0.1:55277 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6311860 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6311863 T15703 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x147d6d07b120002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 6311864 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6311867 T15723 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bbf090 name:ZooKeeperConnection Watcher:127.0.0.1:55277/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6311868 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6311871 T15701 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 6311879 T15701 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 6311888 T15701 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 6311896 T15701 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 6311903 T15701 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 6311915 T15701 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 6311921 T15701 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55284_
[junit4] 2> 6311923 T15701 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55284_
[junit4] 2> 6311930 T15701 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 6311935 T15701 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 6311940 T15701 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 6311945 T15701 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:55284_
[junit4] 2> 6311945 T15701 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 6311952 T15701 oasc.Overseer.start Overseer (id=92278508290703363-127.0.0.1:55284_-n_0000000000) starting
[junit4] 2> 6311959 T15701 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 6311982 T15701 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 6311984 T15725 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 6311985 T15701 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 6311991 T15701 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 6311995 T15701 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6312004 T15724 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 6312008 T15727 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 6312008 T15727 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6312011 T15727 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6312011 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6312017 T15724 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55284",
[junit4] 2> "node_name":"127.0.0.1:55284_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 6312017 T15724 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 6312017 T15724 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 6312024 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 6313011 T15727 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 6313012 T15727 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 6313015 T15727 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 6313015 T15727 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 6313021 T15727 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6313021 T15727 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\'
[junit4] 2> 6313026 T15727 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 6313028 T15727 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 6313029 T15727 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 6313081 T15727 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 6313090 T15727 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6313092 T15727 oass.ManagedIndexSchemaFactory.create The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 6313095 T15727 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 6313100 T15727 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 6313120 T15727 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 6313125 T15727 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 6313129 T15727 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak
[junit4] 2> 6313138 T15727 oass.ManagedIndexSchemaFactory.zkUgradeToManagedSchema After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 6313139 T15727 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 6313139 T15727 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 6313139 T15727 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\data\
[junit4] 2> 6313140 T15727 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 6313141 T15727 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\data
[junit4] 2> 6313142 T15727 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\data\index/
[junit4] 2> 6313142 T15727 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 6313143 T15727 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\data\index
[junit4] 2> 6313143 T15727 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=69.4375, floorSegmentMB=2.0703125, forceMergeDeletesPctAllowed=22.7933473779105, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 6313149 T15727 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e69998; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6313150 T15727 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6313154 T15727 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 6313156 T15727 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 6313157 T15727 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6313158 T15727 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6313158 T15727 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6313159 T15727 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6313159 T15727 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 6313160 T15727 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 6313161 T15727 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 6313161 T15727 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6313163 T15727 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 6313163 T15727 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 6313164 T15727 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 6313179 T15727 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6313181 T15727 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6313183 T15727 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6313185 T15727 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6313188 T15727 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6313191 T15727 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 6313192 T15727 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 6313193 T15727 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=63.5810546875, floorSegmentMB=1.232421875, forceMergeDeletesPctAllowed=27.327726851762314, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6208566959874049
[junit4] 2> 6313196 T15727 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-002\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e69998; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6313196 T15727 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6313197 T15727 oass.SolrIndexSearcher.<init> Opening Searcher@12f28a6[collection1] main
[junit4] 2> 6313197 T15727 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 6313200 T15727 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6313201 T15727 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 6313202 T15727 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 6313202 T15727 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 6313202 T15727 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6313204 T15727 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 6313204 T15727 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6313205 T15727 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json
[junit4] 2> 6313211 T15727 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json
[junit4] 2> 6313211 T15727 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6313211 T15727 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 6313211 T15727 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 6313214 T15729 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12f28a6[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 6313214 T15727 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 6313217 T15732 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55284 collection:control_collection shard:shard1
[junit4] 2> 6313218 T15701 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 6313219 T15701 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 6313221 T15732 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 6313223 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6313227 T15734 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e79520 name:ZooKeeperConnection Watcher:127.0.0.1:55277/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6313228 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6313230 T15701 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6313234 T15732 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 6313237 T15701 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 6313238 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6313241 T15732 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 6313241 T15732 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C6040 name=collection1 org.apache.solr.core.SolrCore@88e1eb url=http://127.0.0.1:55284/collection1 node=127.0.0.1:55284_ C6040_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:55284, node_name=127.0.0.1:55284_, state=down}
[junit4] 2> 6313241 T15732 C6040 P55284 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55284/collection1/
[junit4] 2> 6313241 T15732 C6040 P55284 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 6313241 T15732 C6040 P55284 oasc.SyncStrategy.syncToMe http://127.0.0.1:55284/collection1/ has no replicas
[junit4] 2> 6313241 T15732 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55284/collection1/ shard1
[junit4] 2> 6313242 T15732 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 6313257 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6313367 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 6313369 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 6313412 T15732 oasc.ZkController.register We are http://127.0.0.1:55284/collection1/ and leader is http://127.0.0.1:55284/collection1/
[junit4] 2> 6313412 T15732 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55284
[junit4] 2> 6313412 T15732 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 6313412 T15732 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 6313412 T15732 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6313416 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6313421 T15724 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55284",
[junit4] 2> "node_name":"127.0.0.1:55284_",
[junit4] 2> "numShards":"4",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 6313528 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 6313529 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 6315795 T15701 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 6315796 T15701 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6315802 T15701 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55296
[junit4] 2> 6315805 T15701 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6315805 T15701 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6315806 T15701 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003
[junit4] 2> 6315806 T15701 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\'
[junit4] 2> 6315866 T15701 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\solr.xml
[junit4] 2> 6315891 T15701 oasc.CoreContainer.<init> New CoreContainer 27059108
[junit4] 2> 6315892 T15701 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\]
[junit4] 2> 6315893 T15701 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6315894 T15701 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6315894 T15701 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6315895 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6315896 T15701 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6315896 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6315896 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6315897 T15701 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6315897 T15701 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6315898 T15701 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 6315899 T15701 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6315899 T15701 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6315900 T15701 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6315901 T15701 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55277/solr
[junit4] 2> 6315901 T15701 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6315903 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6315906 T15746 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6de8b8 name:ZooKeeperConnection Watcher:127.0.0.1:55277 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6315906 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6315909 T15703 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x147d6d07b120005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 6315911 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6315913 T15748 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14f7943 name:ZooKeeperConnection Watcher:127.0.0.1:55277/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6315913 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6315928 T15701 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6316936 T15701 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55296_
[junit4] 2> 6316938 T15701 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55296_
[junit4] 2> 6316954 T15701 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 6316965 T15750 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 6316966 T15750 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6316969 T15750 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6316969 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6316974 T15724 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55296",
[junit4] 2> "node_name":"127.0.0.1:55296_",
[junit4] 2> "numShards":"4",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 6316974 T15724 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4]
[junit4] 2> 6316974 T15724 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 6316980 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6316981 T15749 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6316981 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6317969 T15750 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 6317970 T15750 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 6317973 T15750 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 6317973 T15750 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6317975 T15750 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6317975 T15750 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\'
[junit4] 2> 6317977 T15750 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 6317979 T15750 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 6317979 T15750 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 6318031 T15750 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 6318049 T15750 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6318054 T15750 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 6318061 T15750 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 6318073 T15750 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 6318074 T15750 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 6318074 T15750 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 6318075 T15750 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\data\
[junit4] 2> 6318076 T15750 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 6318076 T15750 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\data
[junit4] 2> 6318077 T15750 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\data\index/
[junit4] 2> 6318077 T15750 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 6318078 T15750 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\data\index
[junit4] 2> 6318079 T15750 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=69.4375, floorSegmentMB=2.0703125, forceMergeDeletesPctAllowed=22.7933473779105, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 6318088 T15750 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e47a5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6318089 T15750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6318093 T15750 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 6318095 T15750 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 6318095 T15750 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6318096 T15750 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6318096 T15750 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6318097 T15750 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6318097 T15750 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 6318098 T15750 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 6318099 T15750 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 6318099 T15750 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6318100 T15750 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 6318100 T15750 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 6318101 T15750 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 6318112 T15750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6318115 T15750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6318118 T15750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6318120 T15750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6318123 T15750 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6318126 T15750 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 6318126 T15750 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 6318128 T15750 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=63.5810546875, floorSegmentMB=1.232421875, forceMergeDeletesPctAllowed=27.327726851762314, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6208566959874049
[junit4] 2> 6318130 T15750 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-003\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e47a5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6318131 T15750 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6318131 T15750 oass.SolrIndexSearcher.<init> Opening Searcher@8b040a[collection1] main
[junit4] 2> 6318132 T15750 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6318134 T15750 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6318135 T15750 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 6318136 T15750 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 6318136 T15750 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 6318137 T15750 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6318139 T15750 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 6318140 T15750 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6318140 T15750 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 6318141 T15750 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 6318141 T15750 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 6318146 T15751 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8b040a[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 6318146 T15750 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 6318149 T15754 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55296 collection:collection1 shard:shard2
[junit4] 2> 6318150 T15701 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 6318151 T15701 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 6318152 T15754 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 6318165 T15754 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 6318170 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6318172 T15754 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 6318172 T15754 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C6041 name=collection1 org.apache.solr.core.SolrCore@9baa9 url=http://127.0.0.1:55296/collection1 node=127.0.0.1:55296_ C6041_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:55296, node_name=127.0.0.1:55296_, state=down}
[junit4] 2> 6318172 T15754 C6041 P55296 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55296/collection1/
[junit4] 2> 6318172 T15754 C6041 P55296 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 6318173 T15754 C6041 P55296 oasc.SyncStrategy.syncToMe http://127.0.0.1:55296/collection1/ has no replicas
[junit4] 2> 6318173 T15754 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55296/collection1/ shard2
[junit4] 2> 6318174 T15754 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 6318191 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6318302 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6318303 T15749 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6318304 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6318347 T15754 oasc.ZkController.register We are http://127.0.0.1:55296/collection1/ and leader is http://127.0.0.1:55296/collection1/
[junit4] 2> 6318347 T15754 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55296
[junit4] 2> 6318347 T15754 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 6318347 T15754 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 6318347 T15754 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6318350 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6318355 T15724 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55296",
[junit4] 2> "node_name":"127.0.0.1:55296_",
[junit4] 2> "numShards":"4",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 6318462 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6318462 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6318462 T15749 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 6320862 T15701 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 6320863 T15701 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6320870 T15701 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55305
[junit4] 2> 6320872 T15701 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6320872 T15701 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6320872 T15701 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004
[junit4] 2> 6320873 T15701 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\'
[junit4] 2> 6320909 T15701 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\solr.xml
[junit4] 2> 6320923 T15701 oasc.CoreContainer.<init> New CoreContainer 32870997
[junit4] 2> 6320923 T15701 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\]
[junit4] 2> 6320924 T15701 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6320924 T15701 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6320924 T15701 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6320925 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6320925 T15701 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6320925 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6320926 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6320926 T15701 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6320926 T15701 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6320926 T15701 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 6320927 T15701 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6320927 T15701 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6320927 T15701 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6320928 T15701 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55277/solr
[junit4] 2> 6320928 T15701 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6320930 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6320934 T15765 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ba8069 name:ZooKeeperConnection Watcher:127.0.0.1:55277 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6320935 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6320938 T15703 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x147d6d07b120007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 6320940 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6320943 T15767 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9c6cc1 name:ZooKeeperConnection Watcher:127.0.0.1:55277/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6320944 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6320959 T15701 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6321968 T15701 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55305_
[junit4] 2> 6321973 T15701 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55305_
[junit4] 2> 6321991 T15701 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 6322001 T15769 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 6322002 T15769 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6322006 T15769 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6322007 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6322018 T15724 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55305",
[junit4] 2> "node_name":"127.0.0.1:55305_",
[junit4] 2> "numShards":"4",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 6322019 T15724 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4
[junit4] 2> 6322019 T15724 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 6322024 T15749 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6322026 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6322025 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6322024 T15768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323007 T15769 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 6323009 T15769 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 6323013 T15769 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 6323013 T15769 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6323017 T15769 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6323018 T15769 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\'
[junit4] 2> 6323023 T15769 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 6323025 T15769 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 6323026 T15769 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 6323087 T15769 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 6323104 T15769 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6323108 T15769 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 6323115 T15769 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 6323132 T15769 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 6323133 T15769 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 6323134 T15769 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 6323136 T15769 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\data\
[junit4] 2> 6323137 T15769 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 6323138 T15769 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\data
[junit4] 2> 6323141 T15769 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\data\index/
[junit4] 2> 6323141 T15769 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 6323142 T15769 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\data\index
[junit4] 2> 6323143 T15769 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=69.4375, floorSegmentMB=2.0703125, forceMergeDeletesPctAllowed=22.7933473779105, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 6323155 T15769 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46c8a9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6323156 T15769 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6323159 T15769 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 6323161 T15769 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 6323162 T15769 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6323162 T15769 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6323163 T15769 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6323163 T15769 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6323164 T15769 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 6323164 T15769 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 6323165 T15769 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 6323165 T15769 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 6323166 T15769 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 6323167 T15769 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 6323167 T15769 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 6323177 T15769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6323180 T15769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6323184 T15769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6323187 T15769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6323188 T15769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 6323192 T15769 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 6323193 T15769 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 6323195 T15769 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=63.5810546875, floorSegmentMB=1.232421875, forceMergeDeletesPctAllowed=27.327726851762314, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6208566959874049
[junit4] 2> 6323198 T15769 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-004\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46c8a9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 6323198 T15769 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 6323199 T15769 oass.SolrIndexSearcher.<init> Opening Searcher@6400f4[collection1] main
[junit4] 2> 6323199 T15769 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6323203 T15769 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6323203 T15769 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 6323205 T15769 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 6323205 T15769 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 6323205 T15769 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6323209 T15769 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 6323211 T15769 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 6323212 T15769 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 6323213 T15769 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 6323213 T15769 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 6323228 T15770 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6400f4[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 6323228 T15769 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 6323230 T15773 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55305 collection:collection1 shard:shard3
[junit4] 2> 6323232 T15701 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 6323233 T15701 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 6323236 T15773 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4] 2> 6323250 T15773 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
[junit4] 2> 6323256 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6323258 T15773 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 6323258 T15773 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C6042 name=collection1 org.apache.solr.core.SolrCore@a7893c url=http://127.0.0.1:55305/collection1 node=127.0.0.1:55305_ C6042_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:55305, node_name=127.0.0.1:55305_, state=down}
[junit4] 2> 6323258 T15773 C6042 P55305 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55305/collection1/
[junit4] 2> 6323259 T15773 C6042 P55305 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 6323259 T15773 C6042 P55305 oasc.SyncStrategy.syncToMe http://127.0.0.1:55305/collection1/ has no replicas
[junit4] 2> 6323259 T15773 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55305/collection1/ shard3
[junit4] 2> 6323260 T15773 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4] 2> 6323277 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6323390 T15768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323392 T15749 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323393 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323394 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323434 T15773 oasc.ZkController.register We are http://127.0.0.1:55305/collection1/ and leader is http://127.0.0.1:55305/collection1/
[junit4] 2> 6323434 T15773 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55305
[junit4] 2> 6323435 T15773 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 6323435 T15773 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 6323435 T15773 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6323438 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6323442 T15724 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55305",
[junit4] 2> "node_name":"127.0.0.1:55305_",
[junit4] 2> "numShards":"4",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 6323550 T15768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323550 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323550 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6323550 T15749 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 6325962 T15701 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 6325964 T15701 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 6325970 T15701 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55314
[junit4] 2> 6325971 T15701 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 6325972 T15701 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 6325972 T15701 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005
[junit4] 2> 6325973 T15701 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\'
[junit4] 2> 6326036 T15701 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\solr.xml
[junit4] 2> 6326071 T15701 oasc.CoreContainer.<init> New CoreContainer 2038995
[junit4] 2> 6326072 T15701 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\]
[junit4] 2> 6326074 T15701 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 6326075 T15701 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 6326075 T15701 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 6326076 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6326077 T15701 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6326078 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6326078 T15701 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6326080 T15701 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6326081 T15701 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6326082 T15701 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 6326083 T15701 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6326084 T15701 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6326085 T15701 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 6326086 T15701 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55277/solr
[junit4] 2> 6326087 T15701 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 6326091 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6326095 T15784 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@118507a name:ZooKeeperConnection Watcher:127.0.0.1:55277 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6326096 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6326108 T15701 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 6326112 T15786 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b09821 name:ZooKeeperConnection Watcher:127.0.0.1:55277/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 6326114 T15701 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 6326133 T15701 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 6327141 T15701 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55314_
[junit4] 2> 6327143 T15701 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55314_
[junit4] 2> 6327157 T15701 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 6327171 T15788 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 6327172 T15788 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 6327174 T15788 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 6327175 T15728 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 6327180 T15724 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55314",
[junit4] 2> "node_name":"127.0.0.1:55314_",
[junit4] 2> "numShards":"4",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 6327180 T15724 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4
[junit4] 2> 6327180 T15724 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4
[junit4] 2> 6327188 T15768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 6327188 T15735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 6327188 T15728 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 6327188 T15749 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 6327188 T15787 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 6328174 T15788 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 6328175 T15788 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 6328176 T15788 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 6328176 T15788 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 6328179 T15788 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 6328179 T15788 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\collection1\'
[junit4] 2> 6328180 T15788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-005/collection1/lib/.svn/' to classloader
[junit4] 2> 6328181 T15788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-005/collection1/lib/classes/' to classloader
[junit4] 2> 6328183 T15788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001/tempDir-005/collection1/lib/README' to classloader
[junit4] 2> 6328231 T15788 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 6328249 T15788 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6328253 T15788 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 6328260 T15788 oass.IndexSchema.readSchema [collection1] Schema name=add-schema-fields-update-processor
[junit4] 2> 6328274 T15788 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 6328275 T15788 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 6328275 T15788 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 6328276 T15788 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\collection1\data\
[junit4] 2> 6328277 T15788 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 6328278 T15788 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\collection1\data
[junit4] 2> 6328280 T15788 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\collection1\data\index/
[junit4] 2> 6328280 T15788 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 6328281 T15788 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestCloudSchemaless-ECB8BAC1D8901F81-001\tempDir-005\collection1\data\index
[junit4] 2> 6328281 T15788 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=69.4375, floorSegmentMB=2.0703125, forceMergeDeletesPctAllowed=22.7933473779105, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12,
[...truncated too long message...]
TTest, MoreLikeThisHandlerTest, TestRecoveryHdfs, SuggesterFSTTest, SOLR749Test, MBeansHandlerTest, ChangedSchemaMergeTest, DistribDocExpirationUpdateProcessorTest, BasicDistributedZkTest, TestConfig, TestSchemaSimilarityResource, TestDocumentBuilder, CSVRequestHandlerTest, TestSolrXmlPersistence, FieldFacetExtrasTest, TestWordDelimiterFilterFactory, TestUpdate, TestRestManager, TestPartialUpdateDeduplication, ChaosMonkeyNothingIsSafeTest, TestFoldingMultitermQuery, TestSolrCoreProperties, AnalysisErrorHandlingTest, TestSolr4Spatial2, IndexSchemaRuntimeFieldTest, TestBinaryField, CoreContainerCoreInitFailuresTest, XsltUpdateRequestHandlerTest, TestSolrQueryParserResource, TestOverriddenPrefixQueryForCustomFieldType, TestManagedStopFilterFactory, BadIndexSchemaTest, SuggesterTSTTest, StressHdfsTest, CustomCollectionTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestJmxMonitoredMap, TestSolrQueryParserDefaultOperatorResource, CopyFieldTest, TestShardHandlerFactory, SolrCoreTest, AbstractAnalyticsStatsTest, TestSolrJ, UnloadDistributedZkTest, SolrInfoMBeanTest, TestIndexingPerformance, UUIDUpdateProcessorFallbackTest, TestReRankQParserPlugin, TestDocSet, DistributedQueryComponentOptimizationTest, TestCharFilters, NoCacheHeaderTest, TermVectorComponentDistributedTest, TestFieldTypeCollectionResource, TestPostingsSolrHighlighter, TestReplicationHandlerBackup, TestStressVersions, ExternalFileFieldSortTest, SampleTest, HighlighterMaxOffsetTest, TestRecovery, TestLFUCache, ClusterStateTest, TestStressUserVersions, DistributedExpandComponentTest, TestLazyCores, TestUniqueKeyFieldResource, ScriptEngineTest, SearchHandlerTest, TestCoreDiscovery, TestCopyFieldCollectionResource, TestModifyConfFiles, PingRequestHandlerTest, StatelessScriptUpdateProcessorFactoryTest, TestConfigSets, TestSimpleQParserPlugin, TestManagedResource, TestWriterPerf, AnalyticsMergeStrategyTest, TestPhraseSuggestions, TestFuzzyAnalyzedSuggestions, TestFastWriter, TestStandardQParsers, TestReplicationHandler, TestLMJelinekMercerSimilarityFactory, HdfsDirectoryTest, MergeStrategyTest, DistributedQueryComponentCustomSortTest, TestAnalyzedSuggestions, PreAnalyzedUpdateProcessorTest, TestRangeQuery, SystemInfoHandlerTest, BasicDistributedZk2Test, TestCSVLoader, SignatureUpdateProcessorFactoryTest, TestQueryTypes, PrimitiveFieldTypeTest, TestShortCircuitedRequests, DistribCursorPagingTest, TestSolrXmlPersistor, BinaryUpdateRequestHandlerTest, TestStressRecovery, TestManagedResourceStorage, TestZkChroot, UpdateParamsTest, DocValuesMissingTest, DirectSolrSpellCheckerTest, DateFieldTest, TestStressReorder, TriLevelCompositeIdRoutingTest, TestExpandComponent, TestBinaryResponseWriter, TestDefaultSimilarityFactory, TestFieldSortValues, PreAnalyzedFieldTest, TestFunctionQuery, TestDistributedGrouping, TestMultiCoreConfBootstrap, TestManagedSchemaFieldResource, DistributedQueryElevationComponentTest, TestMergePolicyConfig, TestMiniSolrCloudCluster, CoreAdminCreateDiscoverTest, TestXIncludeConfig, TestCustomSort, AnalysisAfterCoreReloadTest, IndexSchemaTest, HighlighterTest, TestRTGBase, TestOmitPositions, TestFieldCollectionResource, BasicFunctionalityTest, PolyFieldTest, ExpressionTest, RegexBoostProcessorTest, SolrIndexSplitterTest, TestJmxIntegration, CurrencyFieldXmlFileTest, TestSurroundQueryParser, TestDocBasedVersionConstraints, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, AutoCommitTest, TestFaceting, DistributedSpellCheckComponentTest, TestRealTimeGet, TestJoin, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest, ConvertedLegacyTest, TestSort, TestFiltering, TestBadConfig, OverseerCollectionProcessorTest, TestIndexSearcher, SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, TestExtendedDismaxParser, SpellCheckCollatorTest, CoreAdminHandlerTest, SolrRequestParserTest, DocValuesTest, SuggesterTest, TestStressLucene, TestTrie, SpatialFilterTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, FileBasedSpellCheckerTest, TermsComponentTest, RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir, FastVectorHighlighterTest, SolrPluginUtilsTest, TestCSVResponseWriter, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, TestQuerySenderListener, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestSolrDeletionPolicy2, MultiTermTest, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, OutputWriterTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, LegacyHTMLStripCharFilterTest, TimeZoneUtilsTest, ResourceLoaderTest, URLClassifyProcessorTest, PluginInfoTest, SpellingQueryConverterTest, DOMUtilTest, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, CursorPagingTest, DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestTolerantSearch, NoFacetTest, QueryFacetTest, FunctionTest, AliasIntegrationTest, AssignTest, AsyncMigrateRouteKeyTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DeleteShardTest, HttpPartitionTest, MigrateRouteKeyTest, MultiThreadedOCPTest, OverseerRolesTest, OverseerStatusTest, RemoteQueryErrorTest, RollingRestartTest, SSLMigrationTest, ShardSplitTest, SolrXmlInZkTest, TestCloudInspectUtil, TestCloudPivotFacet, TestCollectionAPI, TestLeaderElectionZkExpiry, HdfsBasicDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest, HdfsSyncSliceTest, HdfsUnloadDistributedZkTest, CachingDirectoryFactoryTest, QueryResultKeyTest, TestImplicitCoreProperties, TestNRTOpen, TestNonNRTOpen, TestReloadAndDeleteDocs, TestSolrXml, CoreAdminRequestStatusTest, CoreMergeIndexesAdminHandlerTest, DistributedDebugComponentTest, DistributedFacetPivotLargeTest, DistributedFacetPivotLongTailTest, DistributedFacetPivotSmallTest, DistributedSuggestComponentTest, SuggestComponentTest, TestPivotHelperCode, TestIntervalFaceting, TestClassNameShortening, TestDefaultSearchFieldResource, TestDynamicFieldResource, TestFieldTypeResource, TestSchemaNameResource, TestSchemaResource, TestSchemaVersionResource, TestManagedSynonymFilterFactory, DateRangeFieldTest, EnumFieldTest, ModifyConfFileTest, TestCloudManagedSchema, TestCloudManagedSchemaConcurrent, TestCloudSchemaless]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCloudSchemaless -Dtests.seed=ECB8BAC1D8901F81 -Dtests.slow=true -Dtests.locale=it_IT -Dtests.timezone=Greenwich -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s | TestCloudSchemaless (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.schema.TestCloudSchemaless:
[junit4] > 1) Thread[id=15704, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestCloudSchemaless]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:146)
[junit4] > at __randomizedtesting.SeedInfo.seed([ECB8BAC1D8901F81]:0)
[junit4] Completed in -392.83s, 1 test, 1 error <<< FAILURES!
[...truncated 81 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:474: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:454: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1307: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:922: There were test failures: 429 suites, 1780 tests, 1 suite-level error, 45 ignored (24 assumptions)
Total time: 142 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_20-ea-b23 -client -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any