Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/5615/ Java: 32bit/jdk1.8.0_72 -server -XX:+UseConcMarkSweepGC
1 tests failed. FAILED: org.apache.solr.handler.component.DistributedSpellCheckComponentTest.test Error Message: Error from server at http://127.0.0.1:61834//collection1: Directory SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\spellchecker1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1493d73 still has pending deleted files; cannot initialize IndexWriter Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:61834//collection1: Directory SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\spellchecker1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1493d73 still has pending deleted files; cannot initialize IndexWriter at __randomizedtesting.SeedInfo.seed([4E0F860904B715B2:C65BB9D3AA4B784A]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957) at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.q(DistributedSpellCheckComponentTest.java:67) at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.test(DistributedSpellCheckComponentTest.java:146) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:990) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) 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:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 10502 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_4E0F860904B715B2-001\init-core-data-001 [junit4] 2> 423977 INFO (SUITE-DistributedSpellCheckComponentTest-seed#[4E0F860904B715B2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 423977 INFO (SUITE-DistributedSpellCheckComponentTest-seed#[4E0F860904B715B2]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 424359 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1 [junit4] 2> 424361 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 424363 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@844758{/,null,AVAILABLE} [junit4] 2> 424365 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.ServerConnector Started ServerConnector@d54774{HTTP/1.1}{127.0.0.1:61822} [junit4] 2> 424365 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.Server Started @429174ms [junit4] 2> 424365 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=61822, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores} [junit4] 2> 424365 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93 [junit4] 2> 424365 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control' [junit4] 2> 424365 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 424365 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 424366 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\solr.xml [junit4] 2> 424377 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores [junit4] 2> 424377 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer New CoreContainer 21997899 [junit4] 2> 424377 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control] [junit4] 2> 424377 WARN (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\lib to classpath: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\lib [junit4] 2> 424378 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 424381 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 424382 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 424382 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 424382 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 424382 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 424384 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores [junit4] 2> 424385 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\, configSet=collection1} [junit4] 2> 424385 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1 [junit4] 2> 424385 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 424386 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 424386 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1' [junit4] 2> 424386 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 424387 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 424387 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 424387 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 424387 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 424448 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 424460 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 424485 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 424493 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 424587 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 424598 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 424599 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 424604 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 424606 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 424608 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 424608 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 424617 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1' [junit4] 2> 424617 INFO (coreLoadExecutor-641-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1 [junit4] 2> 424618 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.NRTCachingDirectoryFactory [junit4] 2> 424618 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrCore [[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_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\] [junit4] 2> 424618 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@eb87e5 [junit4] 2> 424620 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data [junit4] 2> 424620 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrCore 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_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\index/ [junit4] 2> 424621 WARN (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 424622 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\index [junit4] 2> 424622 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7335988687665952] [junit4] 2> 424636 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy 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.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8628dc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 424636 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 424638 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\ [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 424640 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 424641 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 424641 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 424641 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 424647 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 424648 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 424649 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 424650 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 424653 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 424655 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 424655 WARN (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 424655 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 424656 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 424656 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 424658 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 424658 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 424658 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=68.533203125, floorSegmentMB=0.8505859375, forceMergeDeletesPctAllowed=24.89385626101895, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.711750236921066 [junit4] 2> 424659 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy 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.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8628dc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 424659 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 424659 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d2d747[collection1] main] [junit4] 2> 424659 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 424659 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 424659 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 424659 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 424707 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 424821 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 424821 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 424822 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 424822 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 424822 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 424822 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 424822 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 424822 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 424822 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 424822 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1526049897341517824 [junit4] 2> 424822 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 424823 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 424823 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 424823 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 424824 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d2d747[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 424826 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 3.0ms to seed version buckets with highest version 1526049897341517824 [junit4] 2> 424826 INFO (coreLoadExecutor-641-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 425450 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1 [junit4] 2> 425453 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 425456 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1946fc8{/,null,AVAILABLE} [junit4] 2> 425459 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.ServerConnector Started ServerConnector@17e4ce5{HTTP/1.1}{127.0.0.1:61825} [junit4] 2> 425460 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.Server Started @430269ms [junit4] 2> 425460 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=61825, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores} [junit4] 2> 425460 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93 [junit4] 2> 425460 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0' [junit4] 2> 425460 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 425460 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 425461 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\solr.xml [junit4] 2> 425474 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores [junit4] 2> 425474 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer New CoreContainer 6844760 [junit4] 2> 425474 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0] [junit4] 2> 425474 WARN (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\lib to classpath: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\lib [junit4] 2> 425475 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 425482 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 425483 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 425483 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 425483 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 425483 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 425496 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores [junit4] 2> 425498 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\, configSet=collection1} [junit4] 2> 425498 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1 [junit4] 2> 425498 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 425498 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1' [junit4] 2> 425499 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 425499 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 425499 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 425499 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 425499 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 425499 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 425560 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 425579 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 425600 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 425606 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 425698 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 425707 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 425707 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 425711 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 425712 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 425714 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 425714 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 425720 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1' [junit4] 2> 425720 INFO (coreLoadExecutor-652-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1 [junit4] 2> 425720 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.NRTCachingDirectoryFactory [junit4] 2> 425721 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrCore [[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_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\] [junit4] 2> 425721 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@eb87e5 [junit4] 2> 425841 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data [junit4] 2> 425842 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrCore 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_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\index/ [junit4] 2> 425842 WARN (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 425843 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\index [junit4] 2> 425843 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7335988687665952] [junit4] 2> 425921 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy 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.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@50ca8e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 425921 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 425922 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\ [junit4] 2> 425923 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 425924 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 425929 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 425930 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 425931 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 425932 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 425939 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 425940 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 425941 WARN (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 425941 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 425942 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 425942 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 425942 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 425942 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 425943 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=68.533203125, floorSegmentMB=0.8505859375, forceMergeDeletesPctAllowed=24.89385626101895, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.711750236921066 [junit4] 2> 425943 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy 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.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@50ca8e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> 425944 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 425944 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c31fb4[collection1] main] [junit4] 2> 425944 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 425944 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 425944 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 425944 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 426034 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 426392 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 426392 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 426392 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 426392 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 426392 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 426393 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 426393 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 426393 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 426393 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 426393 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1526049898988830720 [junit4] 2> 426393 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 426393 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 426394 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 426394 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 426394 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c31fb4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 426395 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1526049898988830720 [junit4] 2> 426395 INFO (coreLoadExecutor-652-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 426502 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 426534 INFO (qtp31820086-1540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1526049899105222656)} 0 31 [junit4] 2> 426539 INFO (qtp13194330-1558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1526049899139825664)} 0 2 [junit4] 2> 426544 INFO (qtp31820086-1543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1526049899144019968)]} 0 2 [junit4] 2> 426549 INFO (qtp13194330-1559) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1526049899150311424)]} 0 2 [junit4] 2> 426552 INFO (qtp31820086-1542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1526049899154505728)]} 0 0 [junit4] 2> 426554 INFO (qtp13194330-1560) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1526049899157651456)]} 0 0 [junit4] 2> 426557 INFO (qtp31820086-1545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1526049899160797184)]} 0 0 [junit4] 2> 426559 INFO (qtp13194330-1561) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1526049899162894336)]} 0 0 [junit4] 2> 426561 INFO (qtp31820086-1541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1526049899164991488)]} 0 0 [junit4] 2> 426563 INFO (qtp13194330-1562) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1526049899167088640)]} 0 0 [junit4] 2> 426572 INFO (qtp31820086-1544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1526049899169185792)]} 0 7 [junit4] 2> 426593 INFO (qtp13194330-1563) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1526049899178622976)]} 0 19 [junit4] 2> 426597 INFO (qtp31820086-1540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1526049899200643072)]} 0 1 [junit4] 2> 426601 INFO (qtp13194330-1558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1526049899204837376)]} 0 2 [junit4] 2> 426604 INFO (qtp31820086-1543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1526049899210080256)]} 0 0 [junit4] 2> 426607 INFO (qtp13194330-1559) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1526049899213225984)]} 0 0 [junit4] 2> 426609 INFO (qtp31820086-1542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1526049899215323136)]} 0 0 [junit4] 2> 426610 INFO (qtp13194330-1560) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1526049899216371712)]} 0 0 [junit4] 2> 426612 INFO (qtp31820086-1545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1526049899218468864)]} 0 0 [junit4] 2> 426614 INFO (qtp13194330-1561) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1526049899220566016)]} 0 0 [junit4] 2> 426623 INFO (qtp31820086-1541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1526049899222663168)]} 0 7 [junit4] 2> 426642 INFO (qtp13194330-1562) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1526049899232100352)]} 0 16 [junit4] 2> 426645 INFO (qtp31820086-1544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1526049899252023296)]} 0 1 [junit4] 2> 426649 INFO (qtp13194330-1563) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1526049899255169024)]} 0 1 [junit4] 2> 426650 INFO (qtp31820086-1540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1526049899258314752)]} 0 0 [junit4] 2> 426653 INFO (qtp13194330-1558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1526049899261460480)]} 0 0 [junit4] 2> 426655 INFO (qtp31820086-1543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1526049899263557632)]} 0 0 [junit4] 2> 426657 INFO (qtp13194330-1559) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1526049899265654784)]} 0 0 [junit4] 2> 426659 INFO (qtp31820086-1542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1526049899267751936)]} 0 0 [junit4] 2> 426661 INFO (qtp13194330-1560) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1526049899269849088)]} 0 0 [junit4] 2> 426669 INFO (qtp31820086-1545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1526049899271946240)]} 0 5 [junit4] 2> 426688 INFO (qtp13194330-1561) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1526049899280334848)]} 0 16 [junit4] 2> 426691 INFO (qtp31820086-1541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1526049899300257792)]} 0 0 [junit4] 2> 426694 INFO (qtp13194330-1562) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1526049899302354944)]} 0 1 [junit4] 2> 426696 INFO (qtp31820086-1544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1526049899306549248)]} 0 0 [junit4] 2> 426698 INFO (qtp13194330-1563) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1526049899308646400)]} 0 0 [junit4] 2> 426700 INFO (qtp31820086-1540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1526049899310743552)]} 0 0 [junit4] 2> 426702 INFO (qtp13194330-1558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1526049899312840704)]} 0 0 [junit4] 2> 426704 INFO (qtp31820086-1543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1526049899314937856)]} 0 0 [junit4] 2> 426706 INFO (qtp13194330-1559) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1526049899317035008)]} 0 0 [junit4] 2> 426714 INFO (qtp31820086-1542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1526049899319132160)]} 0 5 [junit4] 2> 426741 INFO (qtp13194330-1560) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1526049899327520768)]} 0 25 [junit4] 2> 426744 INFO (qtp31820086-1545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1526049899355832320)]} 0 0 [junit4] 2> 426748 INFO (qtp13194330-1561) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1526049899358978048)]} 0 2 [junit4] 2> 426751 INFO (qtp31820086-1541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1526049899364220928)]} 0 0 [junit4] 2> 426753 INFO (qtp13194330-1562) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1526049899366318080)]} 0 0 [junit4] 2> 426756 INFO (qtp31820086-1544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1526049899369463808)]} 0 0 [junit4] 2> 426758 INFO (qtp13194330-1563) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1526049899371560960)]} 0 0 [junit4] 2> 426761 INFO (qtp31820086-1540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1526049899373658112)]} 0 0 [junit4] 2> 426763 INFO (qtp13194330-1558) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1526049899376803840)]} 0 0 [junit4] 2> 426770 INFO (qtp31820086-1543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1526049899378900992)]} 0 4 [junit4] 2> 426785 INFO (qtp13194330-1559) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1526049899386241024)]} 0 14 [junit4] 2> 426788 INFO (qtp31820086-1542) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 427453 INFO (qtp31820086-1542) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8628dc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8628dc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2} [junit4] 2> 427453 INFO (qtp31820086-1542) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 427455 INFO (qtp31820086-1542) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fc4f7b[collection1] main] [junit4] 2> 427455 INFO (qtp31820086-1542) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 427456 INFO (searcherExecutor-642-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1fc4f7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c5) Uninverting(_1(6.0.0):c5) Uninverting(_2(6.0.0):c5) Uninverting(_3(6.0.0):c5) Uninverting(_4(6.0.0):c5)))} [junit4] 2> 427456 INFO (qtp31820086-1542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 668 [junit4] 2> 427458 INFO (qtp13194330-1560) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 427784 INFO (qtp13194330-1560) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@50ca8e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1} [junit4] 2> commit{dir=NRTCachingDirectory(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard0\cores\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@50ca8e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2} [junit4] 2> 427786 INFO (qtp13194330-1560) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 427789 INFO (qtp13194330-1560) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d7f5d5[collection1] main] [junit4] 2> 427789 INFO (qtp13194330-1560) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 427790 INFO (searcherExecutor-653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d7f5d5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c5) Uninverting(_1(6.0.0):c5) Uninverting(_2(6.0.0):c5) Uninverting(_3(6.0.0):c5) Uninverting(_4(6.0.0):c5)))} [junit4] 2> 427791 INFO (qtp13194330-1560) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 333 [junit4] 2> 428022 INFO (qtp31820086-1545) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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=228 [junit4] 2> 428829 INFO (qtp13194330-1562) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:61825//collection1&rows=10&version=2&spellcheck.build=true&shards.qt=spellCheckCompRH&spellcheck.extendedResults=false&q=*:*&spellcheck=true&NOW=1455354595855&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin} hits=25 status=0 QTime=363 [junit4] 2> 428833 INFO (qtp13194330-1563) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&shards.purpose=64&shard.url=http://127.0.0.1:61825//collection1&version=2&spellcheck.build=true&shards.qt=spellCheckCompRH&spellcheck.extendedResults=false&q=*:*&spellcheck=false&NOW=1455354595855&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> 428834 INFO (qtp13194330-1561) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1 null [junit4] 2> 428834 INFO (qtp13194330-1561) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1 null [junit4] 2> 428834 INFO (qtp13194330-1561) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:61825//collection1&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&spellcheck.build=true&shards.qt=spellCheckCompRH} hits=25 status=0 QTime=805 [junit4] 2> 428841 INFO (qtp31820086-1541) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&spellcheck.q=toyata&distrib=false&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&shards.qt=spellCheckCompRH} hits=25 status=0 QTime=3 [junit4] 2> 428859 INFO (qtp13194330-1559) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:61825//collection1|[ff01::213]:33332/&rows=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=false&q=*:*&spellcheck.q=toyata&spellcheck=true&NOW=1455354596671&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin} hits=25 status=0 QTime=2 [junit4] 2> 428861 INFO (qtp13194330-1560) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&shards.purpose=64&shard.url=[ff01::083]:33332/|http://127.0.0.1:61825//collection1|[ff01::213]:33332/&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=false&q=*:*&spellcheck.q=toyata&spellcheck=false&NOW=1455354596671&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> 428861 INFO (qtp13194330-1558) [ x:collection1] o.a.s.h.c.SpellCheckComponent [ff01::083]:33332/|http://127.0.0.1:61825//collection1|[ff01::213]:33332/ {suggestions={toyata={numFound=2,startOffset=0,endOffset=6,suggestion=[toyota, sonata]}},originalTerms=[toyata]} [junit4] 2> 428861 INFO (qtp13194330-1558) [ x:collection1] o.a.s.h.c.SpellCheckComponent [ff01::083]:33332/|http://127.0.0.1:61825//collection1|[ff01::213]:33332/ null [junit4] 2> 428861 INFO (qtp13194330-1558) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:61825//collection1|[ff01::213]:33332/&spellcheck.q=toyata&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&shards.qt=spellCheckCompRH} hits=25 status=0 QTime=18 [junit4] 2> 428865 INFO (qtp31820086-1544) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&spellcheck.q=toyata&distrib=false&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true} hits=25 status=0 QTime=1 [junit4] 2> 428871 INFO (qtp13194330-1563) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&spellcheck.q=toyata&spellcheck=true&NOW=1455354596695&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin} hits=25 status=0 QTime=0 [junit4] 2> 428873 INFO (qtp13194330-1561) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt&shards.purpose=64&shard.url=http://127.0.0.1:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&spellcheck.q=toyata&spellcheck=false&NOW=1455354596695&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> 428874 INFO (qtp13194330-1562) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/ {suggestions={toyata={numFound=2,startOffset=0,endOffset=6,origFreq=0,suggestion=[{word=toyota,freq=1}, {word=sonata,freq=1}]}},correctlySpelled=true,originalTerms=[toyata]} [junit4] 2> 428874 INFO (qtp13194330-1562) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/ null [junit4] 2> 428874 INFO (qtp13194330-1562) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/&spellcheck.q=toyata&qt=spellCheckCompRH&spellcheck=true&fl=id,lowerfilt&wt=javabin&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true} hits=25 status=0 QTime=5 [junit4] 2> 428877 INFO (qtp31820086-1540) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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> 428924 INFO (qtp13194330-1560) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&shards.purpose=2048&start=0&shard.url=http://127.0.0.1:61825//collection1&rows=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&spellcheck.q=bluo&spellcheck=true&NOW=1455354596707&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin&group.field=id&group=true} status=0 QTime=34 [junit4] 2> 428953 INFO (qtp13194330-1558) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,score&shards.purpose=4&start=0&shard.url=http://127.0.0.1:61825//collection1&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&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&spellcheck.q=bluo&spellcheck=false&NOW=1455354596707&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=4&wt=javabin&group.field=id&group=true} status=0 QTime=7 [junit4] 2> 428968 INFO (qtp13194330-1563) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&qt=spellCheckCompRH&fl=id,lowerfilt,id&shards.purpose=64&shard.url=http://127.0.0.1:61825//collection1&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&spellcheck.q=bluo&spellcheck=false&NOW=1455354596707&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> 428969 INFO (qtp13194330-1559) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1 {suggestions={bluo={numFound=2,startOffset=0,endOffset=4,origFreq=0,suggestion=[{word=blue,freq=1}, {word=glue,freq=1}]}},correctlySpelled=false,originalTerms=[bluo]} [junit4] 2> 428969 INFO (qtp13194330-1559) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1 null [junit4] 2> 428969 INFO (qtp13194330-1559) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1 null [junit4] 2> 428969 INFO (qtp13194330-1559) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={qt=spellCheckCompRH&fl=id,lowerfilt&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=*:*&shards=http://127.0.0.1:61825//collection1&spellcheck.q=bluo&spellcheck=true&spellcheck.count=4&wt=javabin&group.field=id&group=true} status=0 QTime=88 [junit4] 2> 428992 INFO (qtp31820086-1543) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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=21 [junit4] 2> 429019 INFO (qtp13194330-1562) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&shards.purpose=2048&start=0&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:61825//collection1&rows=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=The+quick+reb+fox+jumped+over+the+lazy+brown+dogs&spellcheck=true&NOW=1455354596825&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=5&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=17 [junit4] 2> 429053 INFO (qtp13194330-1561) [ x:collection1] o.a.s.h.c.SpellCheckComponent [ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:61825//collection1 {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.},originalTerms=[the, quick, reb, fox, jumped, over, the, lazy, brown, dogs]} [junit4] 2> 429053 INFO (qtp13194330-1561) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:61825//collection1&spellcheck=true&spellcheck.count=4&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=54 [junit4] 2> 429067 INFO (qtp31820086-1542) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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=12 [junit4] 2> 429084 INFO (qtp13194330-1558) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={spellcheck.collateExtendedResults=true&distrib=false&qt=spellCheckCompRH&fl=id&fl=score&shards.purpose=4&spellcheck.maxCollations=10&start=0&fsv=true&spellcheck.maxCollationTries=10&shard.url=http://127.0.0.1:61825//collection1&rows=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&NOW=1455354596904&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=2 [junit4] 2> 429085 INFO (qtp13194330-1560) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1 {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}},originalTerms=[quock, reb]} [junit4] 2> 429085 INFO (qtp13194330-1560) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:61825//collection1&spellcheck=true&spellcheck.count=10&wt=javabin&spellcheck.collate=true} hits=0 status=0 QTime=8 [junit4] 2> 429092 INFO (qtp31820086-1545) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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> 429117 INFO (qtp13194330-1559) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={spellcheck.collateExtendedResults=false&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&shards.purpose=2048&spellcheck.maxCollations=10&start=0&spellcheck.maxCollationTries=10&shard.url=http://127.0.0.1:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&NOW=1455354596923&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=17 [junit4] 2> 429117 INFO (qtp13194330-1563) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/ {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},originalTerms=[quock, reb]} [junit4] 2> 429117 INFO (qtp13194330-1563) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:61825//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=20 [junit4] 2> 429125 INFO (qtp31820086-1541) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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&group.field=id&group=true&spellcheck.collate=true} hits=0 status=0 QTime=1 [junit4] 2> 429135 INFO (qtp13194330-1561) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={spellcheck.collateExtendedResults=false&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id,score&shards.purpose=2048&spellcheck.maxCollations=1&start=0&spellcheck.maxCollationTries=0&shard.url=http://127.0.0.1:61825//collection1&rows=10&version=2&shards.qt=spellCheckCompRH&spellcheck.extendedResults=true&q=lowerfilt:(%2Bquock+%2Breb)&spellcheck=true&NOW=1455354596955&spellcheck.onlyMorePopular=false&isShard=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=2 [junit4] 2> 429136 INFO (qtp13194330-1562) [ x:collection1] o.a.s.h.c.SpellCheckComponent http://127.0.0.1:61825//collection1 {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)},originalTerms=[quock, reb]} [junit4] 2> 429136 INFO (qtp13194330-1562) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:61825//collection1&spellcheck=true&spellcheck.count=10&wt=javabin&group.field=id&group=true&spellcheck.collate=true} status=0 QTime=7 [junit4] 2> 429146 INFO (qtp31820086-1544) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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&spellcheck.collate=true} hits=1 status=0 QTime=7 [junit4] 2> 429151 INFO (qtp13194330-1560) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={spellcheck.collateExtendedResults=false&distrib=false&qt=spellCheckCompRH&fl=id&fl=score&shards.purpose=4&spellcheck.maxCollations=1&start=0&fsv=true&spellcheck.maxCollationTries=10&shard.url=[ff01::083]:33332/|http://127.0.0.1:61825//collection1 [...truncated too long message...] CoreContainer Shutting down CoreContainer instance=14829652 [junit4] 2> 435804 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2daac1 [junit4] 2> 435804 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 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=13,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=1209,transaction_logs_total_number=1} [junit4] 2> 435805 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState [junit4] 2> 435805 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 435805 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser [junit4] 2> 435806 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 435817 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing NRTCachingDirectoryFactory - 2 directories currently being tracked [junit4] 2> 435817 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard1\cores\collection1\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_4E0F860904B715B2-001\tempDir-001\shard1\cores\collection1\data\index;done=false>>] [junit4] 2> 435817 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard1\cores\collection1\data\index [junit4] 2> 435817 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard1\cores\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard1\cores\collection1\data;done=false>>] [junit4] 2> 435817 INFO (coreCloseExecutor-695-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\shard1\cores\collection1\data [junit4] 2> 435819 INFO (TEST-DistributedSpellCheckComponentTest.test-seed#[4E0F860904B715B2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@d712be{/,null,UNAVAILABLE} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedSpellCheckComponentTest -Dtests.method=test -Dtests.seed=4E0F860904B715B2 -Dtests.slow=true -Dtests.locale=ar-IQ -Dtests.timezone=MST -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 11.8s J0 | DistributedSpellCheckComponentTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:61834//collection1: Directory SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.DistributedSpellCheckComponentTest_4E0F860904B715B2-001\tempDir-001\control\cores\collection1\data\spellchecker1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1493d73 still has pending deleted files; cannot initialize IndexWriter [junit4] > at __randomizedtesting.SeedInfo.seed([4E0F860904B715B2:C65BB9D3AA4B784A]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957) [junit4] > at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.q(DistributedSpellCheckComponentTest.java:67) [junit4] > at org.apache.solr.handler.component.DistributedSpellCheckComponentTest.test(DistributedSpellCheckComponentTest.java:146) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:990) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 435823 INFO (SUITE-DistributedSpellCheckComponentTest-seed#[4E0F860904B715B2]-worker) [ ] o.a.s.SolrTestCaseJ4 ###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_4E0F860904B715B2-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {start1=FST50, range_facet_l_dv=FST50, multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), intDefault=PostingsFormat(name=MockRandom), range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _version_=PostingsFormat(name=MockRandom), lowerfilt1and2=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), end4=PostingsFormat(name=LuceneFixedGap), end3=PostingsFormat(name=MockRandom), end2=FST50, end1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), lowerfilt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), gram1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=FST50, range_facet_i_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), gram2=FST50, gram3=PostingsFormat(name=MockRandom), start3=PostingsFormat(name=LuceneFixedGap), gram4=PostingsFormat(name=LuceneFixedGap), start2=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), word=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), start4=PostingsFormat(name=MockRandom)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ar-IQ, timezone=MST [junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_72 (32-bit)/cpus=3,threads=1,free=42277776,total=101027840 [junit4] 2> NOTE: All tests run in this JVM: [SliceStateTest, TestMinMaxOnMultiValuedField, TestManagedResource, IndexBasedSpellCheckerTest, ZkControllerTest, TestGroupingSearch, SuggestComponentContextFilterQueryTest, TestUniqueKeyFieldResource, OverseerTest, TestDistributedSearch, TestExtendedDismaxParser, TestSolrConfigHandler, DeleteShardTest, SecurityConfHandlerTest, JSONWriterTest, ReturnFieldsTest, TestSimpleQParserPlugin, PingRequestHandlerTest, ChaosMonkeyNothingIsSafeTest, AddSchemaFieldsUpdateProcessorFactoryTest, ClusterStateTest, DistributedSpellCheckComponentTest] [junit4] Completed [50/579 (1!)] on J0 in 11.87s, 1 test, 1 error <<< FAILURES! [...truncated 1746 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:740: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:684: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:59: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:233: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:524: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1457: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1014: There were test failures: 579 suites (8 ignored), 2331 tests, 1 error, 127 ignored (82 assumptions) [seed: 4E0F860904B715B2] Total time: 75 minutes 40 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
