You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/03/25 13:47:28 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0_40) - Build #
4591 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4591/
Java: 64bit/jdk1.8.0_40 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestSolrConfigHandler
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf\params.json: java.nio.file.FileSystemException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf\params.json: The process cannot access the file because it is being used by another process. C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1 C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf\params.json: java.nio.file.FileSystemException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf\params.json: The process cannot access the file because it is being used by another process.
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1\conf
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010\collection1
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-010
at __randomizedtesting.SeedInfo.seed([287F96C71939257F]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:286)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:200)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.rest.schema.TestBulkSchemaAPI.testDeleteAndReplace
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([287F96C71939257F:CA2A382A92C143A1]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.rest.schema.TestBulkSchemaAPI.testDeleteAndReplace(TestBulkSchemaAPI.java:392)
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:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9230 lines...]
[junit4] Suite: org.apache.solr.core.TestSolrConfigHandler
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\init-core-data-001
[junit4] 2> 123492 T589 [] [] [] [] oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 123493 T589 [] [] [] [] oas.SolrTestCaseJ4.setUp ###Starting testUserProp
[junit4] 2> 123837 T589 [] [] [] [] oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 123837 T589 [] [] [] [] oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 123838 T589 [] [] [] [] oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core
[junit4] 2> 123841 T589 [] [] [] [] oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 123845 T589 [] [] [] [] oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@66ad1ff7{/solr,null,AVAILABLE}
[junit4] 2> 123857 T589 [] [] [] [] oejs.AbstractConnector.doStart Started ServerConnector@6d107b5e{HTTP/1.1}{127.0.0.1:51367}
[junit4] 2> 123858 T589 [] [] [] [] oejs.Server.doStart Started @128647ms
[junit4] 2> 123858 T589 [] [] [] [] oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/solr, hostPort=51367, configSetBaseDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores}
[junit4] 2> 123860 T589 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 123860 T589 [] [] [] [] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\'
[junit4] 2> 123884 T589 [] [] [] [] oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\solr.xml
[junit4] 2> 123897 T589 [] [] [] [] oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores
[junit4] 2> 123897 T589 [] [] [] [] oasc.CoreContainer.<init> New CoreContainer 1935760796
[junit4] 2> 123897 T589 [] [] [] [] oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\]
[junit4] 2> 123897 T589 [] [] [] [] oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\lib
[junit4] 2> 123898 T589 [] [] [] [] oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\lib).
[junit4] 2> 123907 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 123907 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 123907 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 123908 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 123908 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 123908 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 123908 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 123909 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 123909 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 123909 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 123909 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 123909 T589 [] [] [] [] oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 123909 T589 [] [] [] [] oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 123909 T589 [] [] [] [] oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 123910 T589 [] [] [] [] oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 123911 T589 [] [] [] [] oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores
[junit4] 2> 123912 T589 [] [] [] [] oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-managed-schema.xml, transient=false, schema=schema-rest.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\, configSet=collection1, dataDir=data\}
[junit4] 2> 123912 T589 [] [] [] [] oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\
[junit4] 2> 123913 T589 [] [] [] [] oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 123913 T601 [] [] [] [] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\'
[junit4] 2> 123916 T601 [] [] [] [] oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-001/collection1/lib/classes/' to classloader
[junit4] 2> 123916 T601 [] [] [] [] oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-001/collection1/lib/README' to classloader
[junit4] 2> 123948 T601 [] [] [] [] oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 123954 T601 [] [] [] [] oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 123968 T601 [] [] [] [] oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-managed-schema.xml
[junit4] 2> 123972 T601 [] [] [] [] oass.ManagedIndexSchemaFactory.readSchemaLocally The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-rest.xml instead
[junit4] 2> 123984 T601 [] [] [] [] oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf\schema-rest.xml
[junit4] 2> 123991 T601 [] [] [] [] oass.IndexSchema.readSchema [collection1] Schema name=test-rest
[junit4] 2> 124280 T601 [] [] [] [] oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 124281 T601 [] [] [] [] oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 124281 T601 [] [] [] [] oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 124283 T601 [] [] [] [] oass.IndexSchema.loadCopyFields WARN Field title is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 124301 T601 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 124303 T601 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 124303 T601 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/german
[junit4] 2> 124303 T601 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/german
[junit4] 2> 124313 T601 [] [] [] [] oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 124315 T601 [] [] [] [] oass.ManagedIndexSchemaFactory.upgradeToManagedSchema After upgrading to managed schema, renamed the non-managed schema C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf\schema-rest.xml to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf\schema-rest.xml.bak
[junit4] 2> 124317 T601 [] [] [] [] oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1
[junit4] 2> 124317 T601 [] [] [] [] oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 124318 T601 [] [] [] [] oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\
[junit4] 2> 124318 T601 [] [] [] [] oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 124348 T601 [] [] [] [] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data
[junit4] 2> 124349 T601 [] [] [] [] oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index/
[junit4] 2> 124349 T601 [] [] [] [] oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index' doesn't exist. Creating new index...
[junit4] 2> 124350 T601 [] [] [] [] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index
[junit4] 2> 124351 T601 [] [] [] [] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=62.134765625, floorSegmentMB=2.19921875, forceMergeDeletesPctAllowed=16.900631041853067, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14567312753375178
[junit4] 2> 124356 T601 [] [] [] [] oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@22695def; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 124356 T601 [] [] [] [] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 124358 T601 [] [] [] [] oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 124360 T601 [] [] [] [] oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 124364 T601 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124365 T601 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124366 T601 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124367 T601 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124390 T601 [] [] [] [] oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 124391 T601 [] [] [] [] oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 124391 T601 [] [] [] [] oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 124391 T601 [] [] [] [] oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 124392 T601 [] [] [] [] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=44.19140625, floorSegmentMB=1.5029296875, forceMergeDeletesPctAllowed=22.711762476548824, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 124393 T601 [] [] [] [] oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@22695def; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 124393 T601 [] [] [] [] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 124393 T601 [] [] [] [] oass.SolrIndexSearcher.<init> Opening Searcher@23711820[collection1] main
[junit4] 2> 124393 T601 [] [] [] [] oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124393 T601 [] [] [] [] oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf}
[junit4] 2> 124393 T601 [] [] [] [] oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124394 T601 [] [] [] [] oasr.RestManager.init Initializing 4 registered ManagedResources
[junit4] 2> 124394 T601 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124395 T601 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/stopwords/english
[junit4] 2> 124396 T601 [] [] [] [] oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124396 T601 [] [] [] [] oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/english
[junit4] 2> 124396 T601 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 124396 T601 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124397 T601 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/stopwords/german
[junit4] 2> 124397 T601 [] [] [] [] oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124397 T601 [] [] [] [] oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/german
[junit4] 2> 124397 T601 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/german
[junit4] 2> 124398 T601 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124399 T601 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/english
[junit4] 2> 124400 T601 [] [] [] [] oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 124402 T601 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 124403 T601 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124403 T601 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/german
[junit4] 2> 124403 T601 [] [] [] [] oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/german
[junit4] 2> 124404 T601 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/german
[junit4] 2> 124404 T601 [] [] [] [] oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 124404 T601 [] [] [] [] oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 124404 T602 [] [] [] [] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23711820[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 124405 T589 [] [] [] [] oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1
[junit4] 2> 124405 T589 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 124405 T589 [] [] [] [] oas.SolrJettyTestBase.createJetty Jetty Assigned Port#51367
[junit4] 2> ASYNC NEW_CORE C154 name=collection1 org.apache.solr.core.SolrCore@38b6eeb3
[junit4] 2> 124424 T593 [] [] [] [collection1] C154 oasc.SolrResourceLoader.persistConfLocally Written confile configoverlay.json
[junit4] 2> 124425 T593 [] [] [] [collection1] C154 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\'
[junit4] 2> 124427 T593 [] [] [] [collection1] C154 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-001/collection1/lib/classes/' to classloader
[junit4] 2> 124428 T593 [] [] [] [collection1] C154 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-001/collection1/lib/README' to classloader
[junit4] 2> ASYNC NEW_CORE C155 name=collection1 org.apache.solr.core.SolrCore@38b6eeb3
[junit4] 2> 124486 T593 [] [] [] [collection1] C155 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 124489 T593 [] [] [] [collection1] C155 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> ASYNC NEW_CORE C156 name=collection1 org.apache.solr.core.SolrCore@38b6eeb3
[junit4] 2> 124506 T593 [] [] [] [collection1] C156 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-managed-schema.xml
[junit4] 2> 124512 T593 [] [] [] [collection1] C156 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 124518 T593 [] [] [] [collection1] C156 oass.IndexSchema.readSchema [collection1] Schema name=test-rest
[junit4] 2> ASYNC NEW_CORE C157 name=collection1 org.apache.solr.core.SolrCore@38b6eeb3
[junit4] 2> 124688 T593 [] [] [] [collection1] C157 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 124689 T593 [] [] [] [collection1] C157 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 124689 T593 [] [] [] [collection1] C157 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 124691 T593 [] [] [] [collection1] C157 oass.IndexSchema.loadCopyFields WARN Field title is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 124693 T593 [] [] [] [collection1] C157 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/german
[junit4] 2> 124693 T593 [] [] [] [collection1] C157 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/german
[junit4] 2> 124694 T593 [] [] [] [collection1] C157 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 124694 T593 [] [] [] [collection1] C157 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 124702 T593 [] [] [] [collection1] C157 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1
[junit4] 2> 124704 T593 [] [] [] [collection1] C157 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\
[junit4] 2> 124705 T593 [] [] [] [collection1] C157 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 124705 T593 [] [] [] [collection1] C157 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index/
[junit4] 2> 124708 T593 [] [] [] [collection1] C157 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 124708 T593 [] [] [] [collection1] C157 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=MODIFIEDA,b=MODIFIEDB}}}
[junit4] 2> 124714 T593 [] [] [] [collection1] C157 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124717 T593 [] [] [] [collection1] C157 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124719 T593 [] [] [] [collection1] C157 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124721 T593 [] [] [] [collection1] C157 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 124724 T593 [] [] [] [collection1] C157 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 124725 T593 [] [] [] [collection1] C157 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 124725 T593 [] [] [] [collection1] C157 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 124726 T593 [] [] [] [collection1] C157 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 124728 T593 [] [] [] [collection1] C157 oass.SolrIndexSearcher.<init> Opening Searcher@25bba82a[collection1] main
[junit4] 2> 124728 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124729 T593 [] [] [] [collection1] C157 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf}
[junit4] 2> 124729 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124730 T593 [] [] [] [collection1] C157 oasr.RestManager.init Initializing 4 registered ManagedResources
[junit4] 2> 124730 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124730 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124730 T593 [] [] [] [collection1] C157 oasr.ManagedResource.processStoredData Loaded initArgs {ignoreCase=false} for /schema/analysis/stopwords/english
[junit4] 2> 124731 T593 [] [] [] [collection1] C157 oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/english
[junit4] 2> 124731 T593 [] [] [] [collection1] C157 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 124732 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124732 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124732 T593 [] [] [] [collection1] C157 oasr.ManagedResource.processStoredData Loaded initArgs {ignoreCase=false} for /schema/analysis/stopwords/german
[junit4] 2> 124733 T593 [] [] [] [collection1] C157 oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/german
[junit4] 2> 124733 T593 [] [] [] [collection1] C157 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/german
[junit4] 2> 124733 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124734 T593 [] [] [] [collection1] C157 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/english
[junit4] 2> 124734 T593 [] [] [] [collection1] C157 oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 124734 T593 [] [] [] [collection1] C157 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 124737 T593 [] [] [] [collection1] C157 oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-001\collection1\conf
[junit4] 2> 124737 T593 [] [] [] [collection1] C157 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/german
[junit4] 2> 124738 T593 [] [] [] [collection1] C157 oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/german
[junit4] 2> 124738 T593 [] [] [] [collection1] C157 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/german
[junit4] 2> 124738 T593 [] [] [] [collection1] C157 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 124739 T593 [] [] [] [collection1] C157 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 124739 T593 [] [] [] [collection1] C157 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 124739 T593 [] [] [] [collection1] C157 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 124739 T605 [] [] [] [collection1] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25bba82a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 124742 T593 [] [] [] [collection1] C157 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=62.134765625, floorSegmentMB=2.19921875, forceMergeDeletesPctAllowed=16.900631041853067, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14567312753375178
[junit4] 2> 124744 T593 [] [] [] [collection1] C157 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@22695def; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 124745 T593 [] [] [] [collection1] C157 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 124745 T593 [] [] [] [collection1] C157 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 124746 T593 [] [] [] [collection1] C157 oass.SolrIndexSearcher.<init> Opening Searcher@128c0c4d[collection1] main
[junit4] 2> 124746 T593 [] [] [] [collection1] C157 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 124746 T593 [] [] [] [collection1] C157 oash.SolrConfigHandler$Command.handleCommands Executed config commands successfully and persited to File System [{"set-user-property":{
[junit4] 2> "my.custom.variable.a":"MODIFIEDA",
[junit4] 2> "my.custom.variable.b":"MODIFIEDB"}}]
[junit4] 2> 124746 T593 [] [] [] [collection1] C157 oasc.SolrCore.execute [collection1] webapp=/solr path=/config params={wt=json} status=0 QTime=330
[junit4] 2> 124747 T605 [] [] [] [collection1] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@128c0c4d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 124747 T593 [] [] [] [collection1] C157 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38b6eeb3
[junit4] 2> 124747 T593 [] [] [] [collection1] C157 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 124748 T593 [] [] [] [collection1] C157 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> ASYNC NEW_CORE C158 name=collection1 org.apache.solr.core.SolrCore@4ab39e3f
[junit4] 2> 124753 T594 [] [] [] [collection1] C158 oasc.SolrCore.execute [collection1] webapp=/solr path=/config/overlay params={wt=json} status=0 QTime=1
[junit4] 2> 124757 T595 [] [] [] [collection1] C158 oasc.SolrCore.execute [collection1] webapp=/solr path=/dump params={json.nl=map&initArgs=true&wt=json} status=0 QTime=1
[junit4] 2> 124760 T589 [] [] [] [] oejs.AbstractConnector.doStop Stopped ServerConnector@6d107b5e{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 124760 T589 [] [] [] [] oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1935760796
[junit4] 2> 124761 T589 [] [] [] [] oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4ab39e3f
[junit4] 2> 124761 T589 [] [] [] [] oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 124762 T589 [] [] [] [] oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 124762 T589 [] [] [] [] oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 124762 T589 [] [] [] [] oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 124767 T589 [] [] [] [] oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 124767 T589 [] [] [] [] oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 124767 T589 [] [] [] [] oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data;done=false>>]
[junit4] 2> 124768 T589 [] [] [] [] oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data
[junit4] 2> 124768 T589 [] [] [] [] oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index;done=false>>]
[junit4] 2> 124769 T589 [] [] [] [] oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-003\cores\core\data\index
[junit4] 2> 124769 T589 [] [] [] [] oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@66ad1ff7{/solr,null,UNAVAILABLE}
[junit4] 2> 124770 T589 [] [] [] [] oas.SolrTestCaseJ4.tearDown ###Ending testUserProp
[junit4] 2> 124774 T589 [] [] [] [] oas.SolrTestCaseJ4.setUp ###Starting testProperty
[junit4] 2> 125081 T589 [] [] [] [] oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 125081 T589 [] [] [] [] oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 125082 T589 [] [] [] [] oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core
[junit4] 2> 125087 T589 [] [] [] [] oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 125091 T589 [] [] [] [] oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@f871109{/solr,null,AVAILABLE}
[junit4] 2> 125092 T589 [] [] [] [] oejs.AbstractConnector.doStart Started ServerConnector@76c67e26{HTTP/1.1}{127.0.0.1:51371}
[junit4] 2> 125093 T589 [] [] [] [] oejs.Server.doStart Started @129940ms
[junit4] 2> 125093 T589 [] [] [] [] oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/solr, hostPort=51371, configSetBaseDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores}
[junit4] 2> 125094 T589 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 125094 T589 [] [] [] [] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\'
[junit4] 2> 125126 T589 [] [] [] [] oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\solr.xml
[junit4] 2> 125148 T589 [] [] [] [] oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores
[junit4] 2> 125148 T589 [] [] [] [] oasc.CoreContainer.<init> New CoreContainer 2018352900
[junit4] 2> 125148 T589 [] [] [] [] oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\]
[junit4] 2> 125149 T589 [] [] [] [] oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\lib
[junit4] 2> 125149 T589 [] [] [] [] oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\lib).
[junit4] 2> 125162 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 125163 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 125163 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 125163 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 125163 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 125163 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 125164 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 125164 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 125164 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 125166 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 125166 T589 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 125166 T589 [] [] [] [] oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 125166 T589 [] [] [] [] oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 125166 T589 [] [] [] [] oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 125166 T589 [] [] [] [] oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 125168 T589 [] [] [] [] oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores
[junit4] 2> 125169 T589 [] [] [] [] oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig-managed-schema.xml, transient=false, schema=schema-rest.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\, configSet=collection1, dataDir=data\}
[junit4] 2> 125169 T589 [] [] [] [] oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\
[junit4] 2> 125169 T589 [] [] [] [] oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 125173 T618 [] [] [] [] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\'
[junit4] 2> 125173 T618 [] [] [] [] oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 125175 T618 [] [] [] [] oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 125209 T618 [] [] [] [] oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 125215 T618 [] [] [] [] oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 125225 T618 [] [] [] [] oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-managed-schema.xml
[junit4] 2> 125228 T618 [] [] [] [] oass.ManagedIndexSchemaFactory.readSchemaLocally The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-rest.xml instead
[junit4] 2> 125229 T618 [] [] [] [] oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf\schema-rest.xml
[junit4] 2> 125234 T618 [] [] [] [] oass.IndexSchema.readSchema [collection1] Schema name=test-rest
[junit4] 2> 125358 T618 [] [] [] [] oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 125359 T618 [] [] [] [] oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 125360 T618 [] [] [] [] oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 125362 T618 [] [] [] [] oass.IndexSchema.loadCopyFields WARN Field title is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 125374 T618 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 125375 T618 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 125375 T618 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/german
[junit4] 2> 125375 T618 [] [] [] [] oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/german
[junit4] 2> 125379 T618 [] [] [] [] oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf\managed-schema
[junit4] 2> 125386 T618 [] [] [] [] oass.ManagedIndexSchemaFactory.upgradeToManagedSchema After upgrading to managed schema, renamed the non-managed schema C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf\schema-rest.xml to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf\schema-rest.xml.bak
[junit4] 2> 125387 T618 [] [] [] [] oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1
[junit4] 2> 125388 T618 [] [] [] [] oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 125388 T618 [] [] [] [] oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\
[junit4] 2> 125388 T618 [] [] [] [] oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 125390 T618 [] [] [] [] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data
[junit4] 2> 125391 T618 [] [] [] [] oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\index/
[junit4] 2> 125392 T618 [] [] [] [] oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\index' doesn't exist. Creating new index...
[junit4] 2> 125393 T618 [] [] [] [] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\index
[junit4] 2> 125394 T618 [] [] [] [] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=62.134765625, floorSegmentMB=2.19921875, forceMergeDeletesPctAllowed=16.900631041853067, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14567312753375178
[junit4] 2> 125398 T618 [] [] [] [] oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4061acf0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 125398 T618 [] [] [] [] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 125401 T618 [] [] [] [] oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 125401 T618 [] [] [] [] oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 125409 T618 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125410 T618 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125411 T618 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125413 T618 [] [] [] [] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125415 T618 [] [] [] [] oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 125416 T618 [] [] [] [] oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 125416 T618 [] [] [] [] oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 125416 T618 [] [] [] [] oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 125417 T618 [] [] [] [] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=44.19140625, floorSegmentMB=1.5029296875, forceMergeDeletesPctAllowed=22.711762476548824, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 125418 T618 [] [] [] [] oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4061acf0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 125418 T618 [] [] [] [] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 125418 T618 [] [] [] [] oass.SolrIndexSearcher.<init> Opening Searcher@23d50e16[collection1] main
[junit4] 2> 125422 T618 [] [] [] [] oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125422 T618 [] [] [] [] oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf}
[junit4] 2> 125422 T618 [] [] [] [] oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125423 T618 [] [] [] [] oasr.RestManager.init Initializing 4 registered ManagedResources
[junit4] 2> 125423 T618 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125424 T618 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/stopwords/english
[junit4] 2> 125424 T618 [] [] [] [] oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125424 T618 [] [] [] [] oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/english
[junit4] 2> 125424 T618 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 125424 T618 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125424 T618 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/stopwords/german
[junit4] 2> 125425 T618 [] [] [] [] oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125425 T618 [] [] [] [] oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/german
[junit4] 2> 125425 T618 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/german
[junit4] 2> 125425 T618 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125426 T618 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/english
[junit4] 2> 125426 T618 [] [] [] [] oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 125426 T618 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 125426 T618 [] [] [] [] oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125427 T618 [] [] [] [] oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/german
[junit4] 2> 125427 T618 [] [] [] [] oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/german
[junit4] 2> 125428 T618 [] [] [] [] oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/german
[junit4] 2> 125428 T618 [] [] [] [] oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 125428 T618 [] [] [] [] oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 125428 T619 [] [] [] [] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23d50e16[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 125429 T589 [] [] [] [] oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1
[junit4] 2> 125429 T589 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 125429 T589 [] [] [] [] oas.SolrJettyTestBase.createJetty Jetty Assigned Port#51371
[junit4] 2> ASYNC NEW_CORE C159 name=collection1 org.apache.solr.core.SolrCore@4cf5c331
[junit4] 2> 125435 T608 [] [] [] [collection1] C159 oasc.SolrCore.execute [collection1] webapp=/solr path=/config params={wt=json} status=0 QTime=1
[junit4] 2> 125446 T611 [] [] [] [collection1] C159 oasc.SolrResourceLoader.persistConfLocally Written confile configoverlay.json
[junit4] 2> 125449 T611 [] [] [] [collection1] C159 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\'
[junit4] 2> 125452 T611 [] [] [] [collection1] C159 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 125453 T611 [] [] [] [collection1] C159 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 125504 T611 [] [] [] [collection1] C159 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> ASYNC NEW_CORE C160 name=collection1 org.apache.solr.core.SolrCore@4cf5c331
[junit4] 2> 125513 T611 [] [] [] [collection1] C160 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> ASYNC NEW_CORE C161 name=collection1 org.apache.solr.core.SolrCore@4cf5c331
[junit4] 2> 125523 T611 [] [] [] [collection1] C161 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-managed-schema.xml
[junit4] 2> 125527 T611 [] [] [] [collection1] C161 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf\managed-schema
[junit4] 2> 125531 T611 [] [] [] [collection1] C161 oass.IndexSchema.readSchema [collection1] Schema name=test-rest
[junit4] 2> ASYNC NEW_CORE C162 name=collection1 org.apache.solr.core.SolrCore@4cf5c331
[junit4] 2> 125646 T611 [] [] [] [collection1] C162 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 125647 T611 [] [] [] [collection1] C162 oass.IndexSchema.readSchema query parser default operator is OR
[junit4] 2> 125647 T611 [] [] [] [collection1] C162 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 125648 T611 [] [] [] [collection1] C162 oass.IndexSchema.loadCopyFields WARN Field title is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 125651 T611 [] [] [] [collection1] C162 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/german
[junit4] 2> 125651 T611 [] [] [] [collection1] C162 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/german
[junit4] 2> 125651 T611 [] [] [] [collection1] C162 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 125651 T611 [] [] [] [collection1] C162 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 125660 T611 [] [] [] [collection1] C162 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1
[junit4] 2> 125725 T611 [] [] [] [collection1] C162 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\
[junit4] 2> 125726 T611 [] [] [] [collection1] C162 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 125727 T611 [] [] [] [collection1] C162 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\index/
[junit4] 2> 125732 T611 [] [] [] [collection1] C162 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 125733 T611 [] [] [] [collection1] C162 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 125735 T611 [] [] [] [collection1] C162 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125737 T611 [] [] [] [collection1] C162 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125739 T611 [] [] [] [collection1] C162 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125741 T611 [] [] [] [collection1] C162 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 125742 T611 [] [] [] [collection1] C162 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 125743 T611 [] [] [] [collection1] C162 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 125744 T611 [] [] [] [collection1] C162 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 10ms; if 100 uncommited docs
[junit4] 2> 125744 T611 [] [] [] [collection1] C162 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 125746 T611 [] [] [] [collection1] C162 oass.SolrIndexSearcher.<init> Opening Searcher@634d3f3e[collection1] main
[junit4] 2> 125747 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125747 T611 [] [] [] [collection1] C162 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf}
[junit4] 2> 125747 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125748 T611 [] [] [] [collection1] C162 oasr.RestManager.init Initializing 4 registered ManagedResources
[junit4] 2> 125748 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125749 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125749 T611 [] [] [] [collection1] C162 oasr.ManagedResource.processStoredData Loaded initArgs {ignoreCase=false} for /schema/analysis/stopwords/english
[junit4] 2> 125750 T611 [] [] [] [collection1] C162 oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/english
[junit4] 2> 125750 T611 [] [] [] [collection1] C162 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 125751 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125751 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _schema_analysis_stopwords_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125751 T611 [] [] [] [collection1] C162 oasr.ManagedResource.processStoredData Loaded initArgs {ignoreCase=false} for /schema/analysis/stopwords/german
[junit4] 2> 125751 T611 [] [] [] [collection1] C162 oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/german
[junit4] 2> 125752 T611 [] [] [] [collection1] C162 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/german
[junit4] 2> 125752 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125752 T611 [] [] [] [collection1] C162 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/english
[junit4] 2> 125752 T611 [] [] [] [collection1] C162 oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 125753 T611 [] [] [] [collection1] C162 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 125753 T611 [] [] [] [collection1] C162 oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_german.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\conf
[junit4] 2> 125757 T611 [] [] [] [collection1] C162 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/german
[junit4] 2> 125757 T611 [] [] [] [collection1] C162 oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/german
[junit4] 2> 125757 T611 [] [] [] [collection1] C162 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/german
[junit4] 2> 125758 T611 [] [] [] [collection1] C162 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 125758 T611 [] [] [] [collection1] C162 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 125758 T611 [] [] [] [collection1] C162 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 125758 T622 [] [] [] [collection1] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@634d3f3e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 125759 T611 [] [] [] [collection1] C162 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 125762 T611 [] [] [] [collection1] C162 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=62.134765625, floorSegmentMB=2.19921875, forceMergeDeletesPctAllowed=16.900631041853067, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14567312753375178
[junit4] 2> 125764 T611 [] [] [] [collection1] C162 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-006\cores\core\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4061acf0; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 125765 T611 [] [] [] [collection1] C162 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 125765 T611 [] [] [] [collection1] C162 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 125765 T611 [] [] [] [collection1] C162 oass.SolrIndexSearcher.<init> Opening Searcher@4d011a06[collection1] main
[junit4] 2> 125766 T611 [] [] [] [collection1] C162 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 125766 T622 [] [] [] [collection1] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4d011a06[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 125766 T611 [] [] [] [collection1] C162 oash.SolrConfigHandler$Command.handleCommands Executed config commands successfully and persited to File System [{"set-property":{
[junit4] 2> "updateHandler.autoCommit.maxDocs":100,
[junit4] 2> "updateHandler.autoCommit.maxTime":10}}]
[junit4] 2> 125766 T611 [] [] [] [collection1] C162 oasc.SolrCore.execute [collection1] webapp=/solr path=/config params={wt=json} status=0 QTime=327
[junit4] 2> 125767 T611 [] [] [] [collection1] C162 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4cf5c331
[junit4] 2> 125767 T611 [] [] [] [collection1] C162 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 125768 T611 [] [] [] [collection1] C162 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> ASYNC NEW_CORE C163 name=collection1 org.apache.solr.core.SolrCore@3e875e43
[junit4] 2> 125785 T612 [] [] [] [collection1] C163 oasc.SolrCore.execute [collection1] webapp=/solr path=/config/overlay params={wt=json} status=0 QTime=2
[junit4] 2> 125789 T613 [] [] [] [collection1] C163 oasc.SolrCore.execute [collection1] webapp=/solr path=/config params={wt=json} status=0 QTime=1
[junit4] 2> 125795 T614 [] [] [] [collection1] C163 oasc.SolrResourceLoader.persistConfLocally Written confile configoverlay.json
[junit4] 2> 125796 T614 [] [] [] [collection1] C163 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestSolrConfigHandler 287F96C71939257F-001\tempDir-004\collection1\'
[junit4] 2> 125797 T614 [] [] [] [collection1] C163 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 125797 T614 [] [] [] [collection1] C163 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J1/temp/solr.core.TestSolrConfigHandler%20287F96C71939257F-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 125817 T614 [] [] [] [collection1] C163 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> ASYNC NEW_CORE C164 name=collection1 org.apache.solr.core.SolrCore@3e875e43
[junit4] 2> 125823 T614 [] [] [] [collection1] C164 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> ASYNC NEW_CORE C165 name=col
[...truncated too long message...]
top Stopped ServerConnector@2da5361c{SSL-http/1.1}{127.0.0.1:0}
[junit4] 2> 2234331 T10700 [] [] [] [] oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1677908829
[junit4] 2> 2234332 T10700 [] [] [] [] oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@28c7e789
[junit4] 2> 2234332 T10700 [] [] [] [] oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2234332 T10700 [] [] [] [] oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2234332 T10700 [] [] [] [] oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2234332 T10700 [] [] [] [] oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2234335 T10700 [] [] [] [] oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2234335 T10700 [] [] [] [] oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2234335 T10700 [] [] [] [] oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.rest.schema.TestBulkSchemaAPI 287F96C71939257F-001\tempDir-009\cores\core\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.rest.schema.TestBulkSchemaAPI 287F96C71939257F-001\tempDir-009\cores\core\data\index;done=false>>]
[junit4] 2> 2234336 T10700 [] [] [] [] oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.rest.schema.TestBulkSchemaAPI 287F96C71939257F-001\tempDir-009\cores\core\data\index
[junit4] 2> 2234336 T10700 [] [] [] [] oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.rest.schema.TestBulkSchemaAPI 287F96C71939257F-001\tempDir-009\cores\core\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.rest.schema.TestBulkSchemaAPI 287F96C71939257F-001\tempDir-009\cores\core\data;done=false>>]
[junit4] 2> 2234336 T10700 [] [] [] [] oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.rest.schema.TestBulkSchemaAPI 287F96C71939257F-001\tempDir-009\cores\core\data
[junit4] 2> 2234337 T10700 [] [] [] [] oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@25613a85{/solr,null,UNAVAILABLE}
[junit4] 2> 2234344 T10700 [] [] [] [] oas.SolrTestCaseJ4.tearDown ###Ending testMultipleAddFieldWithErrors
[junit4] 2> 2234345 T10700 [] [] [] [] oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.rest.schema.TestBulkSchemaAPI 287F96C71939257F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=sr_CS, timezone=America/Dawson_Creek
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=94279296,total=340623360
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrDynamicMBean, SolrCmdDistributorTest, TestBadConfig, ConvertedLegacyTest, TestRangeQuery, DisMaxRequestHandlerTest, SearchHandlerTest, ExternalFileFieldSortTest, TestSystemIdResolver, TestReversedWildcardFilterFactory, TestNamedUpdateProcessors, MoreLikeThisHandlerTest, ResourceLoaderTest, TestJettySolrRunner, CachingDirectoryFactoryTest, SolrXmlInZkTest, TestCursorMarkWithoutUniqueKey, TestJsonRequest, TestQuerySenderNoQuery, TestRemoteStreaming, HighlighterConfigTest, TestSolrConfigHandler, TestReload, ResponseHeaderTest, TestSolrDeletionPolicy2, TestInitQParser, SuggesterTSTTest, TestReplicationHandlerBackup, TestSolrConfigHandlerConcurrent, TestRecovery, TestDocBasedVersionConstraints, TestQuerySenderListener, SolrIndexConfigTest, ConnectionManagerTest, TestSolrQueryParserDefaultOperatorResource, CSVRequestHandlerTest, TestDefaultStatsCache, RegexBoostProcessorTest, DistributedTermsComponentTest, ZkNodePropsTest, CurrencyFieldXmlFileTest, HdfsRecoveryZkTest, OpenExchangeRatesOrgProviderTest, UpdateRequestProcessorFactoryTest, TestMissingGroups, TestShardHandlerFactory, TestFastWriter, FileUtilsTest, TestDynamicFieldCollectionResource, TestUtils, BinaryUpdateRequestHandlerTest, BlockCacheTest, SimpleCollectionCreateDeleteTest, SignatureUpdateProcessorFactoryTest, TestConfig, TestMiniSolrCloudClusterSSL, StatsComponentTest, TestCodecSupport, TestFastOutputStream, UpdateParamsTest, MinimalSchemaTest, TestStressUserVersions, DistributedFacetPivotLargeTest, TestFuzzyAnalyzedSuggestions, TestManagedResourceStorage, SchemaVersionSpecificBehaviorTest, ShardRoutingCustomTest, AsyncMigrateRouteKeyTest, JsonLoaderTest, SharedFSAutoReplicaFailoverTest, SimplePostToolTest, RankQueryTest, TestDefaultSimilarityFactory, HdfsWriteToMultipleCollectionsTest, SpellCheckCollatorTest, HdfsDirectoryTest, TestDistribDocBasedVersion, TestDistributedGrouping, CurrencyFieldOpenExchangeTest, TestConfigReload, TestPHPSerializedResponseWriter, PolyFieldTest, TestManagedResource, TestIntervalFaceting, HdfsDirectoryFactoryTest, TestCoreDiscovery, BlockDirectoryTest, TestInfoStreamLogging, TestSearchPerf, DirectUpdateHandlerOptimizeTest, RAMDirectoryFactoryTest, TestNonDefinedSimilarityFactory, ZkSolrClientTest, DocumentBuilderTest, TestTrie, DateFieldTest, DistributedFacetPivotLongTailTest, TestAnalyzeInfixSuggestions, NotRequiredUniqueKeyTest, TermsComponentTest, BasicZkTest, TestSchemaSimilarityResource, ShardSplitTest, QueryParsingTest, TestSerializedLuceneMatchVersion, AnalysisErrorHandlingTest, TestReRankQParserPlugin, TestRealTimeGet, DistribJoinFromCollectionTest, TestSolr4Spatial, TriLevelCompositeIdRoutingTest, TestSolrDeletionPolicy1, FullSolrCloudDistribCmdsTest, TestCopyFieldCollectionResource, XsltUpdateRequestHandlerTest, TestDistributedMissingSort, TestPseudoReturnFields, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, ShardRoutingTest, RecoveryZkTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, TestRandomDVFaceting, TestDistributedSearch, TestFoldingMultitermQuery, TestStressLucene, SpatialFilterTest, TestCSVLoader, SolrCoreCheckLockOnStartupTest, QueryEqualityTest, DocValuesMultiTest, DistanceFunctionTest, SolrInfoMBeanTest, DebugComponentTest, TestSurroundQueryParser, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, TestArbitraryIndexDir, IndexSchemaTest, JSONWriterTest, MBeansHandlerTest, PingRequestHandlerTest, BadComponentTest, TestMergePolicyConfig, TestDocSet, TestLuceneMatchVersion, SynonymTokenizerTest, EchoParamsTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestJmxMonitoredMap, PluginInfoTest, PreAnalyzedFieldTest, ClusterStateTest, SystemInfoHandlerTest, CircularListTest, TestRTGBase, CursorPagingTest, SolrTestCaseJ4Test, TestSimpleTrackingShardHandler, AliasIntegrationTest, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribDocExpirationUpdateProcessorTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, RollingRestartTest, TestCloudInspectUtil, TestCloudPivotFacet, TestMiniSolrCloudCluster, TestRebalanceLeaders, TestShortCircuitedRequests, ExitableDirectoryReaderTest, TestReqParamsAPI, TestSolrConfigHandlerCloud, DistributedFacetPivotWhiteBoxTest, SuggestComponentTest, TestRawResponseWriter, TestSortingResponseWriter, TestBulkSchemaAPI]
[junit4] Completed on J1 in 3.83s, 3 tests, 1 failure <<< FAILURES!
[...truncated 184 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958: There were test failures: 477 suites, 1896 tests, 1 suite-level error, 1 failure, 58 ignored (30 assumptions)
Total time: 60 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_40 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_40) - Build #
4592 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4592/
Java: 32bit/jdk1.8.0_40 -client -XX:+UseG1GC
3 tests failed.
FAILED: org.apache.solr.cloud.CustomCollectionTest.test
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([B1CFF521B52AFBC:8348C088B5AEC244]:0)
at org.apache.solr.common.cloud.ClusterState$CollectionRef.toString(ClusterState.java:397)
at java.lang.String.valueOf(String.java:2982)
at java.lang.StringBuilder.append(StringBuilder.java:131)
at java.util.AbstractMap.toString(AbstractMap.java:536)
at java.lang.String.valueOf(String.java:2982)
at java.lang.StringBuilder.append(StringBuilder.java:131)
at org.apache.solr.common.cloud.ClusterState.toString(ClusterState.java:231)
at org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:290)
at org.apache.solr.cloud.CustomCollectionTest.test(CustomCollectionTest.java:128)
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:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.ReplicationFactorTest.test
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([B1CFF521B52AFBC:8348C088B5AEC244]:0)
at org.apache.solr.common.cloud.ClusterState$CollectionRef.toString(ClusterState.java:397)
at java.lang.String.valueOf(String.java:2982)
at java.lang.StringBuilder.append(StringBuilder.java:131)
at java.util.AbstractMap.toString(AbstractMap.java:536)
at java.lang.String.valueOf(String.java:2982)
at java.lang.StringBuilder.append(StringBuilder.java:131)
at org.apache.solr.common.cloud.ClusterState.toString(ClusterState.java:231)
at java.lang.String.valueOf(String.java:2982)
at java.lang.StringBuilder.append(StringBuilder.java:131)
at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:123)
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:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.rest.schema.TestBulkSchemaAPI.testDeleteAndReplace
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([B1CFF521B52AFBC:E94951BF90AAC962]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.rest.schema.TestBulkSchemaAPI.testDeleteAndReplace(TestBulkSchemaAPI.java:392)
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:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10161 lines...]
[junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\init-core-data-001
[junit4] 2> 1654919 T11178 [] [] [] [] oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1654923 T11178 [] [] [] [] oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1654924 T11179 [] [] [] [] oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1655015 T11178 [] [] [] [] oasc.ZkTestServer.run start zk server on port:65138
[junit4] 2> 1655015 T11178 [] [] [] [] oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1655017 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1655022 T11186 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c82f66 name:ZooKeeperConnection Watcher:127.0.0.1:65138 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1655024 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1655024 T11178 [] [] [] [] oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1655024 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1655027 T11178 [] [] [] [] oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1655029 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1655031 T11189 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@195ca2a name:ZooKeeperConnection Watcher:127.0.0.1:65138/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1655031 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1655032 T11178 [] [] [] [] oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1655032 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1655034 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1655037 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1655039 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1655042 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1655042 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1655046 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1655047 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1655050 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1655050 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1655053 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1655053 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1655056 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1655057 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1655061 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1655062 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1655065 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1655066 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1655067 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1655068 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1655071 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1655071 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1655074 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1655074 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1655078 T11178 [] [] [] [] oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1655079 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1655404 T11178 [] [] [] [] oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1
[junit4] 2> 1655408 T11178 [] [] [] [] oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1655411 T11178 [] [] [] [] oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1a375b6{/,null,AVAILABLE}
[junit4] 2> 1655413 T11178 [] [] [] [] oejs.AbstractConnector.doStart Started ServerConnector@b0b27a{HTTP/1.1}{127.0.0.1:65145}
[junit4] 2> 1655414 T11178 [] [] [] [] oejs.Server.doStart Started @1649024ms
[junit4] 2> 1655414 T11178 [] [] [] [] oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\tempDir-001/control/data, hostContext=/, hostPort=65145, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores}
[junit4] 2> 1655415 T11178 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 1655415 T11178 [] [] [] [] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\'
[junit4] 2> 1655445 T11178 [] [] [] [] oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\solr.xml
[junit4] 2> 1655460 T11178 [] [] [] [] oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores
[junit4] 2> 1655460 T11178 [] [] [] [] oasc.CoreContainer.<init> New CoreContainer 33024338
[junit4] 2> 1655461 T11178 [] [] [] [] oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\]
[junit4] 2> 1655461 T11178 [] [] [] [] oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\lib
[junit4] 2> 1655461 T11178 [] [] [] [] oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\lib).
[junit4] 2> 1655473 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1655473 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1655473 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1655474 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1655474 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1655474 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1655475 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1655475 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1655475 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1655475 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1655476 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1655476 T11178 [] [] [] [] oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1655476 T11178 [] [] [] [] oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1655476 T11178 [] [] [] [] oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1655476 T11178 [] [] [] [] oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1655477 T11178 [] [] [] [] oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65138/solr
[junit4] 2> 1655477 T11178 [] [] [] [] oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1655477 T11178 [] [] [] [] oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1655479 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1655481 T11203 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16c9ffd name:ZooKeeperConnection Watcher:127.0.0.1:65138 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1655481 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1655482 T11178 [] [] [] [] oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1655484 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1655486 T11206 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e1de26 name:ZooKeeperConnection Watcher:127.0.0.1:65138/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1655486 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1655489 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1655492 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1655494 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1655497 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1655500 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1655502 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1655504 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1655506 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1655507 T11178 [] [] [] [] oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65145_
[junit4] 2> 1655508 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:65145_
[junit4] 2> 1655510 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1655512 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1655514 T11178 [] [] [] [] oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1655515 T11178 [] [] [] [] oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:65145_
[junit4] 2> 1655515 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1655518 T11178 [] [] [] [] oasc.Overseer.start Overseer (id=93539916498403331-127.0.0.1:65145_-n_0000000000) starting
[junit4] 2> 1655520 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1655528 T11178 [] [] [] [] oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1655529 T11208 [] [] [] [] oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1655529 T11178 [] [] [] [] oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1655530 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1655536 T11178 [] [] [] [] oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores
[junit4] 2> 1655538 T11178 [] [] [] [] oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1, collection=control_collection, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=}
[junit4] 2> 1655538 T11178 [] [] [] [] oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\
[junit4] 2> 1655539 T11178 [] [] [] [] oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1655539 T11210 [control_collection] [] [] [collection1] oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 1655539 T11210 [control_collection] [] [] [collection1] oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1655540 T11206 [] [] [] [] oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1655540 T11210 [control_collection] [] [] [collection1] oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1655542 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65145",
[junit4] 2> "node_name":"127.0.0.1:65145_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1655543 T11207 [] [] [] [] oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65145",
[junit4] 2> "node_name":"127.0.0.1:65145_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1655543 T11207 [] [] [] [] oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 1655544 T11207 [] [] [] [] oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1655546 T11206 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656450 T11210 [control_collection] [] [] [collection1] oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1656450 T11210 [control_collection] [] [] [collection1] oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 1656451 T11210 [control_collection] [] [] [collection1] oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1656451 T11210 [control_collection] [] [] [collection1] oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1656452 T11210 [control_collection] [] [] [collection1] oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1656452 T11210 [control_collection] [] [] [collection1] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\'
[junit4] 2> 1656470 T11210 [control_collection] [] [] [collection1] oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1656472 T11210 [control_collection] [] [] [collection1] oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1656485 T11210 [control_collection] [] [] [collection1] oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1656492 T11210 [control_collection] [] [] [collection1] oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1656518 T11210 [control_collection] [] [] [collection1] oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1656520 T11210 [control_collection] [] [] [collection1] oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1656545 T11210 [control_collection] [] [] [collection1] oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1656645 T11210 [control_collection] [] [] [collection1] oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1656656 T11210 [control_collection] [] [] [collection1] oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1656658 T11210 [control_collection] [] [] [collection1] oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1656680 T11210 [control_collection] [] [] [collection1] oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1656683 T11210 [control_collection] [] [] [collection1] oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1656687 T11210 [control_collection] [] [] [collection1] oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1656688 T11210 [control_collection] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1656688 T11210 [control_collection] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1656689 T11210 [control_collection] [] [] [collection1] oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1656690 T11210 [control_collection] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1656690 T11210 [control_collection] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1656690 T11210 [control_collection] [] [] [collection1] oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1656691 T11210 [control_collection] [] [] [collection1] oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1656691 T11210 [control_collection] [] [] [collection1] oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\data\
[junit4] 2> 1656691 T11210 [control_collection] [] [] [collection1] oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eae49a
[junit4] 2> 1656691 T11210 [control_collection] [] [] [collection1] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\data\
[junit4] 2> 1656691 T11210 [control_collection] [] [] [collection1] oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\data\index/
[junit4] 2> 1656692 T11210 [control_collection] [] [] [collection1] oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1656692 T11210 [control_collection] [] [] [collection1] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\control-001\cores\collection1\data\index
[junit4] 2> 1656693 T11210 [control_collection] [] [] [collection1] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=5963407, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1656694 T11210 [control_collection] [] [] [collection1] oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ffd02 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1870e65),segFN=segments_1,generation=1}
[junit4] 2> 1656694 T11210 [control_collection] [] [] [collection1] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1656699 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1656699 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1656699 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1656699 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1656699 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1656699 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1656700 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1656700 T11210 [control_collection] [] [] [collection1] oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1656700 T11210 [control_collection] [] [] [collection1] oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1656705 T11210 [control_collection] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1656706 T11210 [control_collection] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1656708 T11210 [control_collection] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1656710 T11210 [control_collection] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1656715 T11210 [control_collection] [] [] [collection1] oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1656716 T11210 [control_collection] [] [] [collection1] oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1656717 T11210 [control_collection] [] [] [collection1] oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1656717 T11210 [control_collection] [] [] [collection1] oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1656719 T11210 [control_collection] [] [] [collection1] oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1656719 T11210 [control_collection] [] [] [collection1] oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1656719 T11210 [control_collection] [] [] [collection1] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=41.4375, floorSegmentMB=1.904296875, forceMergeDeletesPctAllowed=5.806628488758882, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1656720 T11210 [control_collection] [] [] [collection1] oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14ffd02 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1870e65),segFN=segments_1,generation=1}
[junit4] 2> 1656720 T11210 [control_collection] [] [] [collection1] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1656720 T11210 [control_collection] [] [] [collection1] oass.SolrIndexSearcher.<init> Opening Searcher@7a0a1c[collection1] main
[junit4] 2> 1656720 T11210 [control_collection] [] [] [collection1] oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1656722 T11210 [control_collection] [] [] [collection1] oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1656722 T11210 [control_collection] [] [] [collection1] oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1656722 T11210 [control_collection] [] [] [collection1] oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1656722 T11210 [control_collection] [] [] [collection1] oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1656722 T11210 [control_collection] [] [] [collection1] oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1656723 T11210 [control_collection] [] [] [collection1] oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1656723 T11210 [control_collection] [] [] [collection1] oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1656723 T11210 [control_collection] [] [] [collection1] oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1656724 T11210 [control_collection] [] [] [collection1] oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1656724 T11210 [control_collection] [] [] [collection1] oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1656724 T11211 [control_collection] [] [] [collection1] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a0a1c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1656725 T11214 [control_collection] [shard1] [] [] oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:65145 collection:control_collection shard:shard1
[junit4] 2> 1656725 T11178 [] [] [] [] oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1
[junit4] 2> 1656726 T11178 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1656726 T11214 [control_collection] [shard1] [] [] oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1656727 T11178 [] [] [] [] oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1656728 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1656730 T11217 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10a09f1 name:ZooKeeperConnection Watcher:127.0.0.1:65138/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1656730 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1656730 T11178 [] [] [] [] oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1656730 T11178 [] [] [] [] oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1656732 T11214 [control_collection] [shard1] [] [] oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1656734 T11206 [] [] [] [] oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1656734 T11178 [] [] [] [] oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1656735 T11214 [control_collection] [shard1] [] [] oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1656735 T11178 [] [] [] [] oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2
[junit4] 2> 1656735 T11214 [control_collection] [shard1] [] [] oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1656735 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> ASYNC NEW_CORE C3096 name=collection1 org.apache.solr.core.SolrCore@14ed6e url=http://127.0.0.1:65145/collection1 node=127.0.0.1:65145_ C3096_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:65145, node_name=127.0.0.1:65145_, state=down}
[junit4] 2> 1656735 T11214 [control_collection] [shard1] [] [] C3096 P65145 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:65145/collection1/
[junit4] 2> 1656735 T11178 [] [] [] [] oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1656736 T11214 [control_collection] [shard1] [] [] C3096 P65145 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1656736 T11214 [control_collection] [shard1] [] [] C3096 P65145 oasc.SyncStrategy.syncToMe http://127.0.0.1:65145/collection1/ has no replicas
[junit4] 2> 1656737 T11214 [control_collection] [shard1] [] [] oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:65145/collection1/ shard1
[junit4] 2> 1656737 T11214 [control_collection] [shard1] [] [] oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1656738 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1656738 T11220 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10ac767 name:ZooKeeperConnection Watcher:127.0.0.1:65138/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1656739 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1656739 T11178 [] [] [] [] oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1656742 T11206 [] [] [] [] oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1656742 T11180 [] [] [] [] oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14c520ef7350005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1656744 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 1
[junit4] 2> 1656745 T11207 [] [] [] [] oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 1656746 T11217 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656746 T11206 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656752 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:65145",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 1656753 T11207 [] [] [] [] oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json
[junit4] 2> 1656754 T11217 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656754 T11206 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656758 T11206 [] [] [] [] oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1656759 T11217 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656760 T11206 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656791 T11214 [control_collection] [shard1] [] [] oasc.ZkController.register We are http://127.0.0.1:65145/collection1/ and leader is http://127.0.0.1:65145/collection1/
[junit4] 2> 1656791 T11214 [control_collection] [shard1] [] [] oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:65145
[junit4] 2> 1656791 T11214 [control_collection] [shard1] [] [] oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1656791 T11214 [control_collection] [shard1] [] [collection1] oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 1656791 T11214 [control_collection] [shard1] [] [collection1] oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1656793 T11206 [] [] [] [] oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1656793 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65145",
[junit4] 2> "node_name":"127.0.0.1:65145_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1656793 T11207 [] [] [] [] oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65145",
[junit4] 2> "node_name":"127.0.0.1:65145_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1656888 T11217 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656888 T11206 [] [] [] [] oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1656995 T11178 [] [] [] [] oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1
[junit4] 2> 1656997 T11178 [] [] [] [] oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001
[junit4] 2> 1656998 T11178 [] [] [] [] oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1657001 T11178 [] [] [] [] oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@18dc37a{/,null,AVAILABLE}
[junit4] 2> 1657003 T11178 [] [] [] [] oejs.AbstractConnector.doStart Started ServerConnector@efc3a3{HTTP/1.1}{127.0.0.1:65178}
[junit4] 2> 1657003 T11178 [] [] [] [] oejs.Server.doStart Started @1650774ms
[junit4] 2> 1657004 T11178 [] [] [] [] oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=65178, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores}
[junit4] 2> 1657004 T11178 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 1657004 T11178 [] [] [] [] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\'
[junit4] 2> 1657030 T11178 [] [] [] [] oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\solr.xml
[junit4] 2> 1657038 T11178 [] [] [] [] oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores
[junit4] 2> 1657038 T11178 [] [] [] [] oasc.CoreContainer.<init> New CoreContainer 17160084
[junit4] 2> 1657038 T11178 [] [] [] [] oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\]
[junit4] 2> 1657038 T11178 [] [] [] [] oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\lib
[junit4] 2> 1657039 T11178 [] [] [] [] oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\lib).
[junit4] 2> 1657049 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1657049 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1657049 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1657049 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1657050 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1657050 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1657050 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1657051 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1657051 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1657051 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1657051 T11178 [] [] [] [] oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1657052 T11178 [] [] [] [] oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1657052 T11178 [] [] [] [] oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1657053 T11178 [] [] [] [] oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1657053 T11178 [] [] [] [] oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1657053 T11178 [] [] [] [] oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65138/solr
[junit4] 2> 1657053 T11178 [] [] [] [] oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1657054 T11178 [] [] [] [] oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1657054 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1657056 T11234 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@739930 name:ZooKeeperConnection Watcher:127.0.0.1:65138 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1657057 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1657057 T11178 [] [] [] [] oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1657060 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1657062 T11237 [] [] [] [] oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19407b2 name:ZooKeeperConnection Watcher:127.0.0.1:65138/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1657062 T11178 [] [] [] [] oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1657068 T11178 [] [] [] [] oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1657982 T11178 [] [] [] [] oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65178_
[junit4] 2> 1657984 T11178 [] [] [] [] oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:65178_
[junit4] 2> 1657986 T11178 [] [] [] [] oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1657989 T11178 [] [] [] [] oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores
[junit4] 2> 1657991 T11178 [] [] [] [] oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=}
[junit4] 2> 1657992 T11178 [] [] [] [] oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\
[junit4] 2> 1657992 T11178 [] [] [] [] oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1657994 T11238 [collection1] [] [] [collection1] oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1657994 T11238 [collection1] [] [] [collection1] oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1657996 T11206 [] [] [] [] oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1657997 T11238 [collection1] [] [] [collection1] oasc.ZkController.preRegister Registering watch for external collection collection1
[junit4] 2> 1657997 T11238 [collection1] [] [] [collection1] oascc.ZkStateReader.addZkWatch addZkWatch collection1
[junit4] 2> 1657997 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65178",
[junit4] 2> "node_name":"127.0.0.1:65178_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 1657998 T11238 [collection1] [] [] [collection1] oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1657998 T11207 [] [] [] [] oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:65178",
[junit4] 2> "node_name":"127.0.0.1:65178_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1657998 T11207 [] [] [] [] oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 1657999 T11207 [] [] [] [] oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1657999 T11238 [collection1] [] [] [collection1] oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0
[junit4] 2> 1657999 T11238 [collection1] [] [] [collection1] oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1658093 T11207 [] [] [] [] oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1658094 T11237 [] [] [] [] oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
[junit4] 2> 1658095 T11237 [] [] [] [] oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1
[junit4] 2> 1658908 T11238 [collection1] [] [] [collection1] oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1658908 T11238 [collection1] [] [] [collection1] oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1658909 T11238 [collection1] [] [] [collection1] oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1658909 T11238 [collection1] [] [] [collection1] oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1658911 T11238 [collection1] [] [] [collection1] oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1658911 T11238 [collection1] [] [] [collection1] oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\'
[junit4] 2> 1658928 T11238 [collection1] [] [] [collection1] oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1658931 T11238 [collection1] [] [] [collection1] oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1658939 T11238 [collection1] [] [] [collection1] oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1658943 T11238 [collection1] [] [] [collection1] oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1658954 T11238 [collection1] [] [] [collection1] oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1658955 T11238 [collection1] [] [] [collection1] oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1658965 T11238 [collection1] [] [] [collection1] oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1659070 T11238 [collection1] [] [] [collection1] oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1659078 T11238 [collection1] [] [] [collection1] oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1659079 T11238 [collection1] [] [] [collection1] oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1659094 T11238 [collection1] [] [] [collection1] oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1659097 T11238 [collection1] [] [] [collection1] oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1659100 T11238 [collection1] [] [] [collection1] oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1659101 T11238 [collection1] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1659102 T11238 [collection1] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1659102 T11238 [collection1] [] [] [collection1] oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1659103 T11238 [collection1] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1659103 T11238 [collection1] [] [] [collection1] oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1659104 T11238 [collection1] [] [] [collection1] oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1659104 T11238 [collection1] [] [] [collection1] oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1659104 T11238 [collection1] [] [] [collection1] oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\data\
[junit4] 2> 1659104 T11238 [collection1] [] [] [collection1] oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eae49a
[junit4] 2> 1659105 T11238 [collection1] [] [] [collection1] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\data\
[junit4] 2> 1659105 T11238 [collection1] [] [] [collection1] oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\data\index/
[junit4] 2> 1659105 T11238 [collection1] [] [] [collection1] oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1659105 T11238 [collection1] [] [] [collection1] oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CustomCollectionTest B1CFF521B52AFBC-001\shard-1-001\cores\collection1\data\index
[junit4] 2> 1659107 T11238 [collection1] [] [] [collection1] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=5963407, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1659107 T11238 [collection1] [] [] [collection1] oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@b3ce92 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6970e9),segFN=segments_1,generation=1}
[junit4] 2> 1659108 T11238 [collection1] [] [] [collection1] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1659113 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1659113 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1659113 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1659114 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1659114 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1659114 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1659115 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1659115 T11238 [collection1] [] [] [collection1] oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1659115 T11238 [collection1] [] [] [collection1] oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1659120 T11238 [collection1] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1659121 T11238 [collection1] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1659122 T11238 [collection1] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1659124 T11238 [collection1] [] [] [collection1] oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1659131 T11238 [collection1] [] [] [collection1] oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1659132 T11238 [collection1] [] [] [collection1] oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1659133 T11238 [collection1] [] [] [collection1] oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1659134 T11238 [collection1] [] [] [collection1] oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1659135 T11238 [collection1] [] [] [collection1] oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1659135 T11238 [collection1] [] [] [collection1] oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1659136 T11238 [collection1] [] [] [collection1] oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=41.4375, floorSegmentMB=1.904296875, forceMergeDeletesPctAllowed=5.806628488758882, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1659136 T11238 [collection1] [] [] [collection1] oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@b3ce92 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6970e9),segFN=segments_1,generation=1}
[junit4] 2> 1659136 T11238 [collection1] [] [] [collection1] oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1659136 T11238 [collection1] [] [] [collection1] oass.SolrIndexSearcher.<init> Opening Searcher@12a305c[collection1] main
[junit4] 2> 1659136 T11238 [collection1] [] [] [collection1] oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1659137 T11238 [collection1] [] [] [collection1] oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1659138 T11238 [collection1] [] [] [collection1] oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1659138 T11238 [collection1] [] [] [collection1] oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1659139 T11238 [collection1] [] [] [collection1] oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1659139 T11238 [collection1] [] [] [collection1] oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1659139 T11238 [collection1] [] [] [collection1] oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1659140 T11238 [collection1] [] [] [collection1] oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1659140 T11238 [collection1] [] [] [collection1] oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1659140 T11238 [collection1] [] [] [collection1] oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1659141 T11239 [collection1] [] [] [collection1] oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12a305c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1659142 T11238 [collection1] [] [] [collection1] oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1659143 T11242 [collection1] [shard2] [] [] oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:65178 collection:collection1 shard:shard2
[junit4] 2> 1659143 T11178 [] [] [] [] oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1
[junit4] 2> 1659144 T11178 [] [] [] [] oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1659144 T11242 [collection1] [shard2] [] [] oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 1659150 T11242 [collection1] [shard2] [] [] oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 1659152 T11206 [] [] [] [] oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1659152 T11242 [collection1] [shard2] [] [] oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1659152 T11242 [collection1] [shard2] [] [] oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3097 name=collection1 org.apache.solr.core.SolrCore@95c7c6 url=http://127.0.0.1:65178/collection1 node=127.0.0.1:65178_ C3097_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:65178, node_name=127.0.0.1:65178_, state=down}
[junit4] 2> 1659153 T11242 [collection1] [shard2] [] [] C3097 P65178 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:65178/collection1/
[junit4] 2> 1659153 T11242 [collection1] [shard2] [] [] C3097 P65178 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1659153 T11242 [collection1] [shard2] [] [] C3097 P65178 oasc.SyncStrategy.syncToMe http://127.0.0.1:65178/collection1/ has no replicas
[junit4] 2> 1659153 T11242 [collection1] [shard2] [] [] oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:65178/collection1/ shard2
[junit4] 2> 1659154 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 1659154 T11242 [collection1] [shard2] [] [] oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 1659155 T11207 [] [] [] [] oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1
[junit4] 2> 1659156 T11237 [] [] [] [] oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
[junit4] 2> 1659157 T11237 [] [] [] [] oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2
[junit4] 2> 1659162 T11207 [] [] [] [] oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:65178",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 1659163 T11207 [] [] [] [] oasco.ZkStateWriter.writePendingUpdates going to update_collection
[...truncated too long message...]
2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 2364882 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 36 connections to: http://127.0.0.1:49797/
[junit4] 2> 2364882 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 9 connections to: http://127.0.0.1:49764/
[junit4] 2> 2364883 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 3 connections to: http://127.0.0.1:49807/
[junit4] 2> 2364884 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 7 connections to: http://127.0.0.1:49785/
[junit4] 2> 2364884 T8860 [control_collection] [shard1] [] [collection1] oasc.ReplicationFactorTest.distribTearDown super.distribTearDown complete, closing all socket proxies
[junit4] 2> 2364885 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:49797/
[junit4] 2> 2364885 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:49764/
[junit4] 2> 2364885 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:49807/
[junit4] 2> 2364886 T8860 [control_collection] [shard1] [] [collection1] oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:49785/
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ReplicationFactorTest -Dtests.method=test -Dtests.seed=B1CFF521B52AFBC -Dtests.slow=true -Dtests.locale=sr_BA_#Latn -Dtests.timezone=US/Indiana-Starke -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 467s J0 | ReplicationFactorTest.test <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([B1CFF521B52AFBC:8348C088B5AEC244]:0)
[junit4] > at org.apache.solr.common.cloud.ClusterState$CollectionRef.toString(ClusterState.java:397)
[junit4] > at java.lang.String.valueOf(String.java:2982)
[junit4] > at java.lang.StringBuilder.append(StringBuilder.java:131)
[junit4] > at java.util.AbstractMap.toString(AbstractMap.java:536)
[junit4] > at java.lang.String.valueOf(String.java:2982)
[junit4] > at java.lang.StringBuilder.append(StringBuilder.java:131)
[junit4] > at org.apache.solr.common.cloud.ClusterState.toString(ClusterState.java:231)
[junit4] > at java.lang.String.valueOf(String.java:2982)
[junit4] > at java.lang.StringBuilder.append(StringBuilder.java:131)
[junit4] > at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:123)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2364907 T8860 [control_collection] [shard1] [] [collection1] oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B1CFF521B52AFBC-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {_version_=FSTOrd50, multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), intDefault=FSTOrd50, id=PostingsFormat(name=Memory doPackFST= true), text=Lucene50(blocksize=128), range_facet_l=PostingsFormat(name=LuceneVarGapDocFreqInterval), timestamp=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=sr_BA_#Latn, timezone=US/Indiana-Starke
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40 (32-bit)/cpus=3,threads=1,free=199681408,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestRecoveryHdfs, FieldAnalysisRequestHandlerTest, CollectionsAPIAsyncDistributedZkTest, TestStressReorder, SortByFunctionTest, AnalysisAfterCoreReloadTest, TestManagedSchemaDynamicFieldResource, TestSolrIndexConfig, TestPivotHelperCode, TestCharFilters, TestFieldSortValues, SyncSliceTest, TestFieldResource, TestPostingsSolrHighlighter, AssignTest, TestStressVersions, TestSurroundQueryParser, StressHdfsTest, PeerSyncTest, TestCollectionAPI, DeleteReplicaTest, URLClassifyProcessorTest, DistributedQueryElevationComponentTest, TestNRTOpen, SliceStateTest, DistanceUnitsTest, LeaderFailoverAfterPartitionTest, RecoveryZkTest, ShowFileRequestHandlerTest, TestComponentsName, SoftAutoCommitTest, TestXIncludeConfig, HdfsBasicDistributedZk2Test, TestHashPartitioner, DocExpirationUpdateProcessorFactoryTest, XmlUpdateRequestHandlerTest, PrimUtilsTest, DirectUpdateHandlerTest, TestDynamicFieldResource, SimpleMLTQParserTest, SuggesterWFSTTest, TestElisionMultitermQuery, TestAnalyzedSuggestions, SampleTest, TestPartialUpdateDeduplication, TestSort, TestWordDelimiterFilterFactory, PingRequestHandlerTest, DistributedFacetPivotWhiteBoxTest, HardAutoCommitTest, TestExactSharedStatsCache, TestFastLRUCache, TestChildDocTransformer, TestSchemaVersionResource, TestCustomSort, TestUpdate, TestSweetSpotSimilarityFactory, BadCopyFieldTest, CoreMergeIndexesAdminHandlerTest, DistributedQueryComponentCustomSortTest, TestRestManager, BasicDistributedZkTest, ZkCLITest, TestReplicaProperties, UnloadDistributedZkTest, TimeZoneUtilsTest, DefaultValueUpdateProcessorTest, DistributedIntervalFacetingTest, TestExactStatsCache, TestFaceting, RequestHandlersTest, OverriddenZkACLAndCredentialsProvidersTest, TestManagedSchema, TestSolr4Spatial2, TestAddFieldRealTimeGet, TestLRUStatsCache, QueryResultKeyTest, DistributedQueryComponentOptimizationTest, TestLazyCores, TestFreeTextSuggestions, IndexSchemaRuntimeFieldTest, TestDynamicLoading, TestShortCircuitedRequests, TestLeaderElectionZkExpiry, TestOmitPositions, TestDocumentBuilder, SystemInfoHandlerTest, PathHierarchyTokenizerFactoryTest, OverseerTest, SSLMigrationTest, SolrCoreTest, DistanceFunctionTest, SOLR749Test, DocValuesMultiTest, TestBinaryField, UniqFieldsUpdateProcessorFactoryTest, CacheHeaderTest, CoreAdminHandlerTest, TestCloudSchemaless, DateRangeFieldTest, ActionThrottleTest, MergeStrategyTest, TestHashQParserPlugin, DOMUtilTest, MultiTermTest, DocValuesTest, ChaosMonkeyNothingIsSafeTest, TestHighlightDedupGrouping, CircularListTest, AlternateDirectoryTest, TestCloudInspectUtil, SolrCoreCheckLockOnStartupTest, TestCSVResponseWriter, TestExpandComponent, TestNoOpRegenerator, ChaosMonkeySafeLeaderTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomDVFaceting, TestDistributedSearch, BadIndexSchemaTest, ConvertedLegacyTest, BasicFunctionalityTest, HighlighterTest, CurrencyFieldOpenExchangeTest, TestCoreDiscovery, TestExtendedDismaxParser, SuggesterFSTTest, SuggesterTest, TestCSVLoader, TestPseudoReturnFields, QueryEqualityTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, DebugComponentTest, DisMaxRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, DocumentBuilderTest, TestIndexingPerformance, TestSolrQueryParser, SolrPluginUtilsTest, TestJmxIntegration, JSONWriterTest, TestLFUCache, TestQuerySenderListener, ResponseLogComponentTest, BadComponentTest, TestStressRecovery, TestSolrDeletionPolicy2, NumericFieldsTest, TestConfig, TestSolrCoreProperties, NotRequiredUniqueKeyTest, SpellPossibilityIteratorTest, TestCodecSupport, EchoParamsTest, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, ResourceLoaderTest, PreAnalyzedFieldTest, ClusterStateTest, TestUtils, FileUtilsTest, CursorPagingTest, TestTolerantSearch, AliasIntegrationTest, CloudExitableDirectoryReaderTest, DeleteInactiveReplicaTest, DistribCursorPagingTest, DistributedQueueTest, ExternalCollectionsTest, MultiThreadedOCPTest, OverseerStatusTest, ReplicationFactorTest]
[junit4] Completed on J0 in 467.01s, 1 test, 1 error <<< FAILURES!
[...truncated 135 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958: There were test failures: 477 suites, 1896 tests, 2 errors, 1 failure, 58 ignored (30 assumptions)
Total time: 58 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_40 -client -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any