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/12/19 12:56:50 UTC
[JENKINS-EA] Lucene-Solr-5.x-Linux (64bit/jdk-9-ea+95) - Build #
14954 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/14954/
Java: 64bit/jdk-9-ea+95 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC -XX:-CompactStrings
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Sat Dec 19 04:43:07 MST 2015
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sat Dec 19 04:43:07 MST 2015
at __randomizedtesting.SeedInfo.seed([E8A918C278E24DEE:330218047DCA245D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1419)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:771)
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:520)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java: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:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java: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:367)
at java.lang.Thread.run(Thread.java:747)
Build Log:
[...truncated 10669 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/init-core-data-001
[junit4] 2> 837039 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestDetails
[junit4] 2> 837039 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1
[junit4] 2> 837042 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 837042 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e73e73d{/solr,null,AVAILABLE}
[junit4] 2> 837052 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@624758e3{HTTP/1.1}{127.0.0.1:33018}
[junit4] 2> 837052 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.Server Started @838520ms
[junit4] 2> 837052 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=33018}
[junit4] 2> 837052 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@deb6432
[junit4] 2> 837052 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001'
[junit4] 2> 837053 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 837053 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 837053 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/solr.xml
[junit4] 2> 837056 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/.
[junit4] 2> 837056 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer New CoreContainer 1207872944
[junit4] 2> 837056 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001]
[junit4] 2> 837056 WARN (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/lib
[junit4] 2> 837056 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 837057 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 837058 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 837058 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 837058 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 837058 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 837058 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/.
[junit4] 2> 837063 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 837063 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1
[junit4] 2> 837063 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 837064 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1'
[junit4] 2> 837064 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 837064 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 837064 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 837064 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 837069 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 837071 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 837075 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 837084 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 837086 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 837087 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1'
[junit4] 2> 837087 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1
[junit4] 2> 837087 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 837088 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1/data/]
[junit4] 2> 837088 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 837088 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1/data
[junit4] 2> 837088 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 837088 WARN (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 837088 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1/data/index
[junit4] 2> 837088 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.40561474426064215]
[junit4] 2> 837089 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_1,generation=1}
[junit4] 2> 837089 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 837089 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/./collection1/data/
[junit4] 2> 837090 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 837091 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837091 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837092 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837092 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837092 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 837092 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 837093 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 837093 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 837093 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 837093 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=326273391, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_1,generation=1}
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@2ca89372[collection1] main
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1/conf
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1/conf}
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-001/collection1/conf
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 837094 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 837094 INFO (searcherExecutor-2809-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ca89372[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 837095 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 837565 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1
[junit4] 2> 837567 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 837568 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3763982{/solr,null,AVAILABLE}
[junit4] 2> 837568 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3cbfff09{HTTP/1.1}{127.0.0.1:54027}
[junit4] 2> 837568 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.Server Started @839036ms
[junit4] 2> 837568 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=54027}
[junit4] 2> 837568 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@deb6432
[junit4] 2> 837568 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002'
[junit4] 2> 837569 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 837569 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 837569 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/solr.xml
[junit4] 2> 837571 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/.
[junit4] 2> 837571 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer New CoreContainer 1548559336
[junit4] 2> 837571 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002]
[junit4] 2> 837572 WARN (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/lib
[junit4] 2> 837572 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 837572 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 837573 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 837573 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 837573 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 837573 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 837573 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/.
[junit4] 2> 837574 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 837574 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1
[junit4] 2> 837574 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 837574 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1'
[junit4] 2> 837575 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 837575 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[E8A918C278E24DEE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 837575 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 837575 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 837579 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 837581 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 837585 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 837587 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 837588 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 837589 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1'
[junit4] 2> 837589 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1
[junit4] 2> 837589 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 837589 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/]
[junit4] 2> 837589 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 837589 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data
[junit4] 2> 837589 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 837590 WARN (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 837590 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index
[junit4] 2> 837590 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.40561474426064215]
[junit4] 2> 837590 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_1,generation=1}
[junit4] 2> 837590 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 837590 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/
[junit4] 2> 837591 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 837592 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837592 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837593 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837594 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 837594 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 837594 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 837595 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 837595 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 837595 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 837597 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=326273391, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 837598 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_1,generation=1}
[junit4] 2> 837598 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 837598 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@40203bd2[collection1] main
[junit4] 2> 837598 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1/conf
[junit4] 2> 837598 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1/conf}
[junit4] 2> 837598 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/collection1/conf
[junit4] 2> 837598 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 837599 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 837599 INFO (searcherExecutor-2819-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@40203bd2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 837599 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 837606 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 838076 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 838078 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 838081 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 838081 INFO (qtp111210413-6143) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
[junit4] 2> 838083 INFO (qtp1546527210-6126) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 838084 INFO (qtp1546527210-6127) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 838086 INFO (qtp1546527210-6127) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_2,generation=2}
[junit4] 2> 838086 INFO (qtp1546527210-6127) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 838087 INFO (qtp1546527210-6127) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@62c4854f[collection1] main
[junit4] 2> 838087 INFO (qtp1546527210-6127) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 838087 INFO (searcherExecutor-2809-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@62c4854f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C1)))}
[junit4] 2> 838087 INFO (qtp1546527210-6127) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 838088 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 838090 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 838090 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 838090 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 838090 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 838092 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException: -8
[junit4] 2> at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:73)
[junit4] 2> at org.apache.lucene.store.DataInput.readInt(DataInput.java:101)
[junit4] 2> at org.apache.lucene.store.MockIndexInputWrapper.readInt(MockIndexInputWrapper.java:157)
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.validateFooter(CodecUtil.java:414)
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.retrieveChecksum(CodecUtil.java:401)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getFileList(ReplicationHandler.java:565)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:250)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:156)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2070)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:457)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:222)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:181)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
[junit4] 2> at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:499)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] 2> at java.lang.Thread.run(Thread.java:747)
[junit4] 2> 838093 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 838093 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 838094 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304262
[junit4] 2> 838094 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 838094 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 838094 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_1,generation=1}
[junit4] 2> 838095 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 838095 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 838095 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_1,generation=1}
[junit4] 2> 838095 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 838095 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 838095 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@13e46c5b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eb52503)
[junit4] 2> 838097 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838099 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838102 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_MockRandom_0.tmp&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838103 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838105 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838107 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838109 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838111 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838113 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838115 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838115 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1572) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@13e46c5b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eb52503)
[junit4] 2> 838116 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@1f59f38c
[junit4] 2> 838116 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_2,generation=2}
[junit4] 2> 838116 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 838117 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 838117 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@3e2ba5e0[collection1] main
[junit4] 2> 838117 INFO (searcherExecutor-2819-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e2ba5e0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C1)))}
[junit4] 2> 838117 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304262 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304262;done=true>>]
[junit4] 2> 838117 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304262
[junit4] 2> 838117 INFO (qtp111210413-6144) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:33018/solr/collection1/replication&command=fetchindex} status=0 QTime=29
[junit4] 2> 838121 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 838121 INFO (qtp111210413-6145) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 838123 INFO (qtp1546527210-6126) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 838124 INFO (qtp1546527210-6127) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 838150 INFO (qtp1546527210-6127) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_3,generation=3}
[junit4] 2> 838150 INFO (qtp1546527210-6127) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 838150 INFO (qtp1546527210-6127) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@30a3cba9[collection1] main
[junit4] 2> 838150 INFO (qtp1546527210-6127) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 838150 INFO (searcherExecutor-2809-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@30a3cba9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C1) Uninverting(_1(5.5.0):C1)))}
[junit4] 2> 838151 INFO (qtp1546527210-6127) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 26
[junit4] 2> 838152 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 838154 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 838154 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 838154 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 838154 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 838156 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838156 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _1_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838156 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
[junit4] 2> 838156 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 20
[junit4] 2> 838156 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304324
[junit4] 2> 838157 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 838157 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_2,generation=2}
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_2,generation=2}
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@4bc1d83c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@406ea671)
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_MockRandom_0.doc because it already exists
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_MockRandom_0.tmp because it already exists
[junit4] 2> 838158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 838160 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838160 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 838160 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 838160 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 838160 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 838162 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838164 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838166 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838168 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838170 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.tix&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838172 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838174 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838176 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838178 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838179 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.tbk&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838181 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838182 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1737) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@4bc1d83c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@406ea671)
[junit4] 2> 838182 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Cannot complete replication attempt because file already exists:_0_MockRandom_0.sd
[junit4] 2> 838182 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@2961845c
[junit4] 2> 838182 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea),segFN=segments_2,generation=2}
[junit4] 2> 838182 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 838183 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 838183 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304324 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304324;done=true>>]
[junit4] 2> 838183 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304324
[junit4] 2> 838183 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Replication attempt was not successful - trying a full index replication reloadCore=false
[junit4] 2> 838184 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 838184 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 838184 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 838184 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 838186 WARN (qtp1546527210-6125) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838186 WARN (qtp1546527210-6125) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _1_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838187 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 838187 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 20
[junit4] 2> 838187 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304355
[junit4] 2> 838187 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@170345ca lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d1468ab)
[junit4] 2> 838189 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838191 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838193 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_MockRandom_0.tmp&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838195 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838196 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838198 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838200 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838202 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838204 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838205 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838207 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838209 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838211 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838213 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.tix&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838215 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838217 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838218 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838220 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838222 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_MockRandom_0.tbk&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838224 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838224 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=3135) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@170345ca lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d1468ab)
[junit4] 2> 838224 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher New index installed. Updating index properties... index=index.20151219044304355
[junit4] 2> 838225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(RAMDirectory@3fc8080d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d7d24ea)
[junit4] 2> 838225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 838225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrCore New index directory detected: old=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index/ new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304355
[junit4] 2> 838225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=83.3876953125, floorSegmentMB=0.443359375, forceMergeDeletesPctAllowed=10.937749155904609, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 838226 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@170345ca lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d1468ab),segFN=segments_3,generation=3}
[junit4] 2> 838226 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 838226 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 838226 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@641573d8[collection1] main
[junit4] 2> 838226 INFO (searcherExecutor-2819-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@641573d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C1) Uninverting(_1(5.5.0):C1)))}
[junit4] 2> 838227 INFO (qtp111210413-6143) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:33018/solr/collection1/replication&command=fetchindex} status=0 QTime=75
[junit4] 2> 838227 INFO (qtp111210413-6143) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index;done=true>>]
[junit4] 2> 838227 INFO (qtp111210413-6143) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index
[junit4] 2> 838230 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 838230 INFO (qtp111210413-6144) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 838232 INFO (qtp1546527210-6126) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 838233 INFO (qtp1546527210-6127) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 838235 INFO (qtp1546527210-6127) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=4
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3c0618f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6127c3fd),segFN=segments_4,generation=4}
[junit4] 2> 838235 INFO (qtp1546527210-6127) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 4
[junit4] 2> 838235 INFO (qtp1546527210-6127) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@64974a30[collection1] main
[junit4] 2> 838235 INFO (qtp1546527210-6127) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 838235 INFO (searcherExecutor-2809-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64974a30[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C1) Uninverting(_1(5.5.0):C1) Uninverting(_2(5.5.0):C1)))}
[junit4] 2> 838235 INFO (qtp1546527210-6127) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 838236 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 838238 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 838238 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 838238 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 838238 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 838240 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838240 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _1_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838240 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _2_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838240 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
[junit4] 2> 838240 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 30
[junit4] 2> 838241 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304408
[junit4] 2> 838241 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 838241 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@170345ca lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d1468ab),segFN=segments_3,generation=3}
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@170345ca lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d1468ab),segFN=segments_3,generation=3}
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@17d8f048 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e234c69)
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_MockRandom_0.doc because it already exists
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_MockRandom_0.tmp because it already exists
[junit4] 2> 838242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 838244 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_0_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838244 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 838244 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 838244 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 838244 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 838244 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_MockRandom_0.doc because it already exists
[junit4] 2> 838246 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_1_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_MockRandom_0.tix because it already exists
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
[junit4] 2> 838246 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_MockRandom_0.tbk because it already exists
[junit4] 2> 838248 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_MockRandom_0.tio&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838249 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838251 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838253 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838255 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_MockRandom_0.tipo&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838257 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838258 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838260 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838262 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838264 INFO (qtp1546527210-6126) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838266 INFO (qtp1546527210-6127) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 838266 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1887) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@17d8f048 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e234c69)
[junit4] 2> 838266 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Cannot complete replication attempt because file already exists:_0_MockRandom_0.sd
[junit4] 2> 838266 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6504720e
[junit4] 2> 838267 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@170345ca lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d1468ab),segFN=segments_3,generation=3}
[junit4] 2> 838267 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 838267 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 838267 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304408 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304408;done=true>>]
[junit4] 2> 838267 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-002/./collection1/data/index.20151219044304408
[junit4] 2> 838267 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Replication attempt was not successful - trying a full index replication reloadCore=false
[junit4] 2> 838268 INFO (qtp1546527210-6125) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 838269 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 838269 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 838269 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 838270 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838270 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _1_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838270 WARN (qtp1546527210-6126) [ ] o.a.s.h.ReplicationHandler Could not read checksum from index file: _2_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 838271 INFO (qtp1546527210-612
[...truncated too long message...]
C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-027/./collection1/data/index
[junit4] 2> 916346 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-027/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-027/./collection1/data;done=false>>]
[junit4] 2> 916346 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-027/./collection1/data
[junit4] 2> 916346 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@315a1015{/solr,null,UNAVAILABLE}
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@54b4edd{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=761464539
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1315f9e6
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 916347 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-028/./collection1/data/index.20151219044422401 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-028/./collection1/data/index.20151219044422401;done=false>>]
[junit4] 2> 916348 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-028/./collection1/data/index.20151219044422401
[junit4] 2> 916348 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-028/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-028/./collection1/data;done=false>>]
[junit4] 2> 916348 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001/solr-instance-028/./collection1/data
[junit4] 2> 916348 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[E8A918C278E24DEE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6a33efd{/solr,null,UNAVAILABLE}
[junit4] 2> 916349 INFO (SUITE-TestReplicationHandler-seed#[E8A918C278E24DEE]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_E8A918C278E24DEE-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {name=PostingsFormat(name=MockRandom), id=PostingsFormat(name=MockRandom), newname=PostingsFormat(name=MockRandom)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=zh_HK, timezone=America/Denver
[junit4] 2> NOTE: Linux 3.19.0-37-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=245400976,total=515899392
[junit4] 2> NOTE: All tests run in this JVM: [TestTrieFacet, HdfsRecoverLeaseTest, SaslZkACLProviderTest, TestNRTOpen, SortByFunctionTest, OpenCloseCoreStressTest, CursorMarkTest, TestQuerySenderListener, DistributedExpandComponentTest, BigEndianAscendingWordDeserializerTest, ConnectionManagerTest, TestFieldSortValues, TestRequestForwarding, SpellPossibilityIteratorTest, StressHdfsTest, TestWordDelimiterFilterFactory, TestReRankQParserPlugin, SolrXmlInZkTest, TestCloudPivotFacet, ChaosMonkeyNothingIsSafeTest, TestBulkSchemaAPI, TestTrie, TestCursorMarkWithoutUniqueKey, NotRequiredUniqueKeyTest, TestSerializedLuceneMatchVersion, FastVectorHighlighterTest, TestRandomForceMergePolicy, TestReplicationHandlerBackup, TestUpdate, TestCloudSchemaless, ClusterStateUpdateTest, TestSchemaManager, TestSolr4Spatial2, BasicFunctionalityTest, MinimalSchemaTest, SpellCheckCollatorTest, IndexBasedSpellCheckerTest, TestDefaultStatsCache, TestCoreContainer, DistributedQueueTest, DocValuesTest, TestQueryTypes, TestJettySolrRunner, TestExactStatsCache, CollectionsAPIDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType, ExplicitHLLTest, UpdateRequestProcessorFactoryTest, TestDistribDocBasedVersion, DistributedIntervalFacetingTest, DistributedFacetPivotSmallAdvancedTest, BadCopyFieldTest, SolrInfoMBeanTest, TestOrdValues, TestImplicitCoreProperties, BlockCacheTest, ShardRoutingCustomTest, DistributedMLTComponentTest, TestRawTransformer, SolrPluginUtilsTest, TestSolrCloudWithKerberosAlt, TestSolrCoreProperties, DistanceUnitsTest, CollectionsAPIAsyncDistributedZkTest, QueryResultKeyTest, CachingDirectoryFactoryTest, SoftAutoCommitTest, TestDocumentBuilder, RequestLoggingTest, SolrIndexConfigTest, ShardRoutingTest, SpellingQueryConverterTest, DirectSolrSpellCheckerTest, SolrIndexSplitterTest, HdfsThreadLeakTest, TestSimpleTrackingShardHandler, WordBreakSolrSpellCheckerTest, TestRuleBasedAuthorizationPlugin, AddSchemaFieldsUpdateProcessorFactoryTest, TestMaxScoreQueryParser, TestFileDictionaryLookup, OutOfBoxZkACLAndCredentialsProvidersTest, TestFieldCollectionResource, TestChildDocTransformer, ChaosMonkeySafeLeaderTest, FileUtilsTest, TermsComponentTest, UnloadDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed [274/555 (1!)] on J1 in 79.33s, 13 tests, 1 failure <<< FAILURES!
[...truncated 907 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:794: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:738: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:526: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1456: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1010: There were test failures: 555 suites (7 ignored), 2206 tests, 1 failure, 83 ignored (38 assumptions) [seed: E8A918C278E24DEE]
Total time: 47 minutes 21 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.x-Linux (32bit/jdk1.7.0_80) - Build # 14955
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/14955/
Java: 32bit/jdk1.7.0_80 -client -XX:+UseSerialGC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
ERROR: SolrIndexSearcher opens=51 closes=50
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50
at __randomizedtesting.SeedInfo.seed([75550D481A1BB61D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:453)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:225)
at sun.reflect.GeneratedMethodAccessor26.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java: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:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java: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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([75550D481A1BB61D]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([75550D481A1BB61D]:0)
Build Log:
[...truncated 10926 lines...]
[junit4] Suite: org.apache.solr.core.TestLazyCores
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/init-core-data-001
[junit4] 2> 1002887 INFO (SUITE-TestLazyCores-seed#[75550D481A1BB61D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1002889 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRace
[junit4] 2> 1002908 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001'
[junit4] 2> 1002908 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1002908 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1002908 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreContainer New CoreContainer 32685552
[junit4] 2> 1002908 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001]
[junit4] 2> 1002908 WARN (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/lib
[junit4] 2> 1002908 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1002910 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1002911 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.SolrCores Allocating transient cache for 4 transient cores
[junit4] 2> 1002911 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1002911 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1002911 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1002911 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1002912 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=configsetprops.json, transient=false, name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, dataDir=data/}
[junit4] 2> 1002912 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=true, configSetProperties=configsetprops.json, name=collection2, schema=schema.xml, dataDir=data/, config=solrconfig.xml, loadOnStartup=true}
[junit4] 2> 1002912 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=solrconfig.xml, schema=schema.xml, configSetProperties=configsetprops.json, name=collection3, transient=on, loadOnStartup=false, dataDir=data/}
[junit4] 2> 1002912 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {loadOnStartup=false, configSetProperties=configsetprops.json, config=solrconfig.xml, name=collection4, dataDir=data/, transient=false, schema=schema.xml}
[junit4] 2> 1002913 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection5, loadOnStartup=true, transient=false, config=solrconfig.xml, configSetProperties=configsetprops.json, dataDir=data/, schema=schema.xml}
[junit4] 2> 1002913 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {schema=schema.xml, loadOnStartup=false, dataDir=data/, configSetProperties=configsetprops.json, transient=true, name=collection6, config=solrconfig.xml}
[junit4] 2> 1002913 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {dataDir=data/, config=solrconfig.xml, name=collection7, schema=schema.xml, loadOnStartup=false, transient=true, configSetProperties=configsetprops.json}
[junit4] 2> 1002913 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {loadOnStartup=false, configSetProperties=configsetprops.json, config=solrconfig.xml, name=collection8, transient=true, dataDir=data/, schema=schema.xml}
[junit4] 2> 1002913 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=true, configSetProperties=configsetprops.json, name=collection9, loadOnStartup=false, schema=schema.xml, config=solrconfig.xml, dataDir=data/}
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1'
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2'
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5'
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1002914 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1002926 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1002926 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1002926 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1002949 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1002954 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1002954 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1002973 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1002977 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.s.IndexSchema [collection2] Schema name=tiny
[junit4] 2> 1002985 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1002986 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2'
[junit4] 2> 1002986 INFO (coreLoadExecutor-3740-thread-2) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2
[junit4] 2> 1002987 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1002987 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/]
[junit4] 2> 1002987 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCore JMX monitoring not detected for core: collection2
[junit4] 2> 1002987 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data
[junit4] 2> 1002987 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/index/
[junit4] 2> 1002987 WARN (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCore [collection2] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/index' doesn't exist. Creating new index...
[junit4] 2> 1002987 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/index
[junit4] 2> 1002988 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=32.865234375, floorSegmentMB=0.345703125, forceMergeDeletesPctAllowed=2.6603870568174406, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1002988 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@187fd55 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1090b86),segFN=segments_1,generation=1}
[junit4] 2> 1002988 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1002988 INFO (OldIndexDirectoryCleanupThreadForCore-collection2) [ x:collection2] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection2 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/
[junit4] 2> 1002988 WARN (OldIndexDirectoryCleanupThreadForCore-collection2) [ x:collection2] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1002989 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1002991 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1002992 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1002993 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1002994 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1002994 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1002994 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1002995 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.RequestHandlers Registered paths: /admin/file,/update/json,/admin/luke,/schema,/admin/properties,/admin/logging,/get,/admin/mbeans,/update/json/docs,/select,/admin/ping,/admin/system,/admin/plugins,/replication,/update/csv,/admin/threads,/admin/segments,/update,/config
[junit4] 2> 1002996 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1002996 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1002996 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1003000 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.s.IndexSchema [collection5] Schema name=tiny
[junit4] 2> 1003000 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=tiny
[junit4] 2> 1003008 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=85.044921875, floorSegmentMB=1.9951171875, forceMergeDeletesPctAllowed=11.984815752536024, segmentsPerTier=38.0, maxCFSSegmentSizeMB=1.375, noCFSRatio=0.7384303483184754
[junit4] 2> 1003008 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@187fd55 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1090b86),segFN=segments_1,generation=1}
[junit4] 2> 1003008 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003008 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.s.SolrIndexSearcher Opening Searcher@101320[collection2] main
[junit4] 2> 1003009 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/conf
[junit4] 2> 1003009 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/conf}
[junit4] 2> 1003009 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/conf
[junit4] 2> 1003009 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1003009 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1003009 INFO (searcherExecutor-3741-thread-1-processing-x:collection2) [ x:collection2] o.a.s.c.SolrCore [collection2] Registered new searcher Searcher@101320[collection2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1003009 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.SolrCores Opening transient core collection2
[junit4] 2> 1003010 INFO (coreLoadExecutor-3740-thread-2) [ x:collection2] o.a.s.c.CoreContainer registering core: collection2
[junit4] 2> 1003024 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1003024 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1003025 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5'
[junit4] 2> 1003025 INFO (coreLoadExecutor-3740-thread-3) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5
[junit4] 2> 1003025 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1003025 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/]
[junit4] 2> 1003025 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrCore JMX monitoring not detected for core: collection5
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1'
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/index/
[junit4] 2> 1003026 WARN (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrCore [collection5] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/index' doesn't exist. Creating new index...
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/]
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/index
[junit4] 2> 1003026 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data
[junit4] 2> 1003039 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/index/
[junit4] 2> 1003039 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=32.865234375, floorSegmentMB=0.345703125, forceMergeDeletesPctAllowed=2.6603870568174406, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1003039 WARN (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1003039 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/index
[junit4] 2> 1003040 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@883ca8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46755d),segFN=segments_1,generation=1}
[junit4] 2> 1003040 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=32.865234375, floorSegmentMB=0.345703125, forceMergeDeletesPctAllowed=2.6603870568174406, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1003040 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003040 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@c888c2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18a7e15),segFN=segments_1,generation=1}
[junit4] 2> 1003040 INFO (OldIndexDirectoryCleanupThreadForCore-collection5) [ x:collection5] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection5 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/
[junit4] 2> 1003040 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003040 WARN (OldIndexDirectoryCleanupThreadForCore-collection5) [ x:collection5] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1003040 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/
[junit4] 2> 1003040 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1003041 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1003041 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1003043 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003043 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003043 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003044 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003044 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003044 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003045 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003046 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003046 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.RequestHandlers Registered paths: /admin/file,/update/json,/admin/luke,/schema,/admin/properties,/admin/logging,/get,/admin/mbeans,/update/json/docs,/select,/admin/ping,/admin/system,/admin/plugins,/replication,/update/csv,/admin/threads,/admin/segments,/update,/config
[junit4] 2> 1003046 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/file,/update/json,/admin/luke,/schema,/admin/properties,/admin/logging,/get,/admin/mbeans,/update/json/docs,/select,/admin/ping,/admin/system,/admin/plugins,/replication,/update/csv,/admin/threads,/admin/segments,/update,/config
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=85.044921875, floorSegmentMB=1.9951171875, forceMergeDeletesPctAllowed=11.984815752536024, segmentsPerTier=38.0, maxCFSSegmentSizeMB=1.375, noCFSRatio=0.7384303483184754
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=85.044921875, floorSegmentMB=1.9951171875, forceMergeDeletesPctAllowed=11.984815752536024, segmentsPerTier=38.0, maxCFSSegmentSizeMB=1.375, noCFSRatio=0.7384303483184754
[junit4] 2> 1003047 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@883ca8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46755d),segFN=segments_1,generation=1}
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@c888c2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18a7e15),segFN=segments_1,generation=1}
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.s.SolrIndexSearcher Opening Searcher@17e68b0[collection5] main
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1b93131[collection1] main
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/conf
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/conf}
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/conf
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/conf
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/conf}
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/conf
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1003048 INFO (searcherExecutor-3745-thread-1-processing-x:collection5) [ x:collection5] o.a.s.c.SolrCore [collection5] Registered new searcher Searcher@17e68b0[collection5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-3) [ x:collection5] o.a.s.c.CoreContainer registering core: collection5
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1003048 INFO (searcherExecutor-3746-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b93131[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1003048 INFO (coreLoadExecutor-3740-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1003050 INFO (Thread-2735) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3'
[junit4] 2> 1003050 INFO (Thread-2735) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1003050 INFO (Thread-2735) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1003056 INFO (Thread-2735) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1003073 INFO (Thread-2735) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1003107 INFO (Thread-2735) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1003111 INFO (Thread-2735) [ ] o.a.s.s.IndexSchema [collection3] Schema name=tiny
[junit4] 2> 1003118 INFO (Thread-2735) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1003120 INFO (Thread-2735) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3'
[junit4] 2> 1003120 INFO (Thread-2735) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection3' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3
[junit4] 2> 1003120 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1003120 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrCore [[collection3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/]
[junit4] 2> 1003121 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrCore JMX monitoring not detected for core: collection3
[junit4] 2> 1003121 INFO (Thread-2735) [ x:collection3] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data
[junit4] 2> 1003121 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/index/
[junit4] 2> 1003121 WARN (Thread-2735) [ x:collection3] o.a.s.c.SolrCore [collection3] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/index' doesn't exist. Creating new index...
[junit4] 2> 1003121 INFO (Thread-2735) [ x:collection3] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/index
[junit4] 2> 1003122 INFO (Thread-2735) [ x:collection3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=32.865234375, floorSegmentMB=0.345703125, forceMergeDeletesPctAllowed=2.6603870568174406, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1003122 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1750625 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e686a8),segFN=segments_1,generation=1}
[junit4] 2> 1003122 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003123 INFO (OldIndexDirectoryCleanupThreadForCore-collection3) [ x:collection3] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection3 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/
[junit4] 2> 1003123 WARN (OldIndexDirectoryCleanupThreadForCore-collection3) [ x:collection3] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1003124 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1003126 INFO (Thread-2735) [ x:collection3] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003127 INFO (Thread-2735) [ x:collection3] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003129 INFO (Thread-2735) [ x:collection3] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003130 INFO (Thread-2735) [ x:collection3] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003131 INFO (Thread-2735) [ x:collection3] o.a.s.c.RequestHandlers Registered paths: /admin/file,/update/json,/admin/luke,/schema,/admin/properties,/admin/logging,/get,/admin/mbeans,/update/json/docs,/select,/admin/ping,/admin/system,/admin/plugins,/replication,/update/csv,/admin/threads,/admin/segments,/update,/config
[junit4] 2> 1003132 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1003132 INFO (Thread-2735) [ x:collection3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1003132 INFO (Thread-2735) [ x:collection3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1003132 INFO (Thread-2735) [ x:collection3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=85.044921875, floorSegmentMB=1.9951171875, forceMergeDeletesPctAllowed=11.984815752536024, segmentsPerTier=38.0, maxCFSSegmentSizeMB=1.375, noCFSRatio=0.7384303483184754
[junit4] 2> 1003133 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1750625 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e686a8),segFN=segments_1,generation=1}
[junit4] 2> 1003133 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003133 INFO (Thread-2735) [ x:collection3] o.a.s.s.SolrIndexSearcher Opening Searcher@6f17e8[collection3] main
[junit4] 2> 1003133 INFO (Thread-2735) [ x:collection3] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/conf
[junit4] 2> 1003133 INFO (Thread-2735) [ x:collection3] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/conf}
[junit4] 2> 1003133 INFO (Thread-2735) [ x:collection3] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/conf
[junit4] 2> 1003134 INFO (Thread-2735) [ x:collection3] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1003134 INFO (Thread-2735) [ x:collection3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1003134 INFO (searcherExecutor-3753-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.SolrCore [collection3] Registered new searcher Searcher@6f17e8[collection3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1003134 INFO (Thread-2735) [ x:collection3] o.a.s.c.SolrCores Opening transient core collection3
[junit4] 2> 1003134 INFO (Thread-2735) [ x:collection3] o.a.s.c.CoreContainer registering core: collection3
[junit4] 2> 1003136 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=32685552
[junit4] 2> 1003136 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.c.SolrCore [collection5] CLOSING SolrCore org.apache.solr.core.SolrCore@1a6f359
[junit4] 2> 1003136 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.u.DirectUpdateHandler2 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> 1003136 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1003136 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1003136 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1003137 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.c.SolrCore [collection5] Closing main searcher on request.
[junit4] 2> 1003137 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1003137 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data;done=false>>]
[junit4] 2> 1003137 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data
[junit4] 2> 1003137 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/index;done=false>>]
[junit4] 2> 1003137 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection5] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection5/data/index
[junit4] 2> 1003138 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1923331
[junit4] 2> 1003138 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 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> 1003138 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1003138 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1003138 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1003139 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1003139 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1003139 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data;done=false>>]
[junit4] 2> 1003139 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data
[junit4] 2> 1003139 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/index;done=false>>]
[junit4] 2> 1003139 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection1/data/index
[junit4] 2> 1003139 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.c.SolrCore [collection2] CLOSING SolrCore org.apache.solr.core.SolrCore@df1155
[junit4] 2> 1003140 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.u.DirectUpdateHandler2 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> 1003140 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1003140 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1003140 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.c.SolrCore [collection2] Closing main searcher on request.
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data;done=false>>]
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/index;done=false>>]
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection2] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection2/data/index
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.SolrCore [collection3] CLOSING SolrCore org.apache.solr.core.SolrCore@dcb2e8
[junit4] 2> 1003141 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.u.DirectUpdateHandler2 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> 1003142 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1003142 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1003142 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1003143 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.SolrCore [collection3] Closing main searcher on request.
[junit4] 2> 1003143 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1003143 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/index;done=false>>]
[junit4] 2> 1003143 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data/index
[junit4] 2> 1003143 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data;done=false>>]
[junit4] 2> 1003143 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-001/collection3/data
[junit4] 2> 1003143 INFO (TEST-TestLazyCores.testRace-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.SolrTestCaseJ4 ###Ending testRace
[junit4] 2> 1003146 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.SolrTestCaseJ4 ###Starting testLazyLoad
[junit4] 2> 1003161 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002'
[junit4] 2> 1003161 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1003161 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1003161 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreContainer New CoreContainer 28734653
[junit4] 2> 1003161 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002]
[junit4] 2> 1003162 WARN (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/lib
[junit4] 2> 1003162 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1003163 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1003163 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.SolrCores Allocating transient cache for 4 transient cores
[junit4] 2> 1003163 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1003163 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1003163 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1003163 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1003164 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {dataDir=data/, transient=false, schema=schema.xml, loadOnStartup=true, config=solrconfig.xml, configSetProperties=configsetprops.json, name=collection1}
[junit4] 2> 1003164 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {loadOnStartup=true, transient=true, configSetProperties=configsetprops.json, config=solrconfig.xml, name=collection2, dataDir=data/, schema=schema.xml}
[junit4] 2> 1003164 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=on, loadOnStartup=false, schema=schema.xml, dataDir=data/, configSetProperties=configsetprops.json, config=solrconfig.xml, name=collection3}
[junit4] 2> 1003164 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {loadOnStartup=false, schema=schema.xml, name=collection4, dataDir=data/, transient=false, config=solrconfig.xml, configSetProperties=configsetprops.json}
[junit4] 2> 1003164 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=configsetprops.json, schema=schema.xml, name=collection5, transient=false, dataDir=data/, loadOnStartup=true, config=solrconfig.xml}
[junit4] 2> 1003165 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=true, config=solrconfig.xml, configSetProperties=configsetprops.json, name=collection6, loadOnStartup=false, schema=schema.xml, dataDir=data/}
[junit4] 2> 1003165 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {dataDir=data/, configSetProperties=configsetprops.json, loadOnStartup=false, schema=schema.xml, transient=true, name=collection7, config=solrconfig.xml}
[junit4] 2> 1003165 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=true, schema=schema.xml, loadOnStartup=false, config=solrconfig.xml, dataDir=data/, configSetProperties=configsetprops.json, name=collection8}
[junit4] 2> 1003165 INFO (TEST-TestLazyCores.testLazyLoad-seed#[75550D481A1BB61D]) [ x:collection3] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=configsetprops.json, transient=true, loadOnStartup=false, dataDir=data/, name=collection9, config=solrconfig.xml, schema=schema.xml}
[junit4] 2> 1003165 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1'
[junit4] 2> 1003165 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2'
[junit4] 2> 1003166 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1003166 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5'
[junit4] 2> 1003166 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1003166 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1003166 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1003166 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1003166 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1003175 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1003175 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1003175 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1003195 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1003197 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1003197 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1003220 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1003223 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1003224 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1003224 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.s.IndexSchema [collection2] Schema name=tiny
[junit4] 2> 1003226 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.s.IndexSchema [collection1] Schema name=tiny
[junit4] 2> 1003228 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.s.IndexSchema [collection5] Schema name=tiny
[junit4] 2> 1003242 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1003243 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2'
[junit4] 2> 1003243 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection3] o.a.s.c.CoreContainer Creating SolrCore 'collection2' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2
[junit4] 2> 1003243 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1003243 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrCore [[collection2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2/data/]
[junit4] 2> 1003243 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrCore JMX monitoring not detected for core: collection2
[junit4] 2> 1003244 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1003244 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2/data
[junit4] 2> 1003244 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2/data/index/
[junit4] 2> 1003244 WARN (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrCore [collection2] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2/data/index' doesn't exist. Creating new index...
[junit4] 2> 1003244 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2/data/index
[junit4] 2> 1003244 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=32.865234375, floorSegmentMB=0.345703125, forceMergeDeletesPctAllowed=2.6603870568174406, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13629f3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc2bcf),segFN=segments_1,generation=1}
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1'
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection3] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1003245 INFO (OldIndexDirectoryCleanupThreadForCore-collection2) [ x:collection2] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection2 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2/data/
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1/data/]
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1003245 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1003245 WARN (OldIndexDirectoryCleanupThreadForCore-collection2) [ x:collection2] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection2/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1/data
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1/data/index/
[junit4] 2> 1003247 WARN (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5'
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection3] o.a.s.c.CoreContainer Creating SolrCore 'collection5' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1/data/index
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=32.865234375, floorSegmentMB=0.345703125, forceMergeDeletesPctAllowed=2.6603870568174406, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrCore [[collection5] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5/data/]
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrCore JMX monitoring not detected for core: collection5
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1092b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@189d446),segFN=segments_1,generation=1}
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5/data
[junit4] 2> 1003247 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5/data/index/
[junit4] 2> 1003248 WARN (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrCore [collection5] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5/data/index' doesn't exist. Creating new index...
[junit4] 2> 1003248 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1/data/
[junit4] 2> 1003248 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5/data/index
[junit4] 2> 1003248 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1003248 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=32.865234375, floorSegmentMB=0.345703125, forceMergeDeletesPctAllowed=2.6603870568174406, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1003248 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d38149 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cce08e),segFN=segments_1,generation=1}
[junit4] 2> 1003248 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1003248 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003248 INFO (OldIndexDirectoryCleanupThreadForCore-collection5) [ x:collection5] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection5 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5/data/
[junit4] 2> 1003249 WARN (OldIndexDirectoryCleanupThreadForCore-collection5) [ x:collection5] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_75550D481A1BB61D-001/tempDir-002/collection5/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1003249 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1003249 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003249 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1003250 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003250 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003250 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [ x:collection5] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003251 INFO (coreLoadExecutor-3762-thread-1-processing-x:collection3) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003251 INFO (coreLoadExecutor-3762-thread-2-processing-x:collection3) [ x:collection2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1003251 INFO (coreLoadExecutor-3762-thread-3-processing-x:collection3) [
[...truncated too long message...]
> Dec 19, 2015 2:26:01 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> Dec 19, 2015 2:26:21 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
[junit4] 2> 1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Dec 19, 2015 2:26:21 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> Dec 19, 2015 2:26:24 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {v_t=FST50, v_s=PostingsFormat(name=Asserting), id=FST50, v_f=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{}, sim=DefaultSimilarity, locale=pt_BR, timezone=Canada/Yukon
[junit4] 2> NOTE: Linux 3.19.0-37-generic i386/Oracle Corporation 1.7.0_80 (32-bit)/cpus=12,threads=2,free=116856568,total=351309824
[junit4] 2> NOTE: All tests run in this JVM: [HighlighterMaxOffsetTest, SchemaVersionSpecificBehaviorTest, TestReRankQParserPlugin, HttpPartitionTest, TestUniqueKeyFieldResource, TestMaxScoreQueryParser, SimplePostToolTest, TestConfigSetProperties, DistributedTermsComponentTest, DocValuesTest, DeleteLastCustomShardedReplicaTest, CursorMarkTest, HighlighterTest, SuggesterFSTTest, TimeZoneUtilsTest, TestRandomForceMergePolicy, TestSurroundQueryParser, TestSolrConfigHandlerCloud, UniqFieldsUpdateProcessorFactoryTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestFieldSortValues, TestSolrConfigHandler, OpenExchangeRatesOrgProviderTest, TestPartialUpdateDeduplication, TestStressVersions, ZkControllerTest, ClusterStateUpdateTest, TestSchemaResource, ConnectionManagerTest, SortSpecParsingTest, CollectionsAPIDistributedZkTest, FileUtilsTest, OpenCloseCoreStressTest, CloudMLTQParserTest, TestDefaultStatsCache, TestChildDocTransformer, TestBM25SimilarityFactory, CollectionReloadTest, BlockCacheTest, TestRuleBasedAuthorizationPlugin, RecoveryAfterSoftCommitTest, TestLeaderElectionZkExpiry, TestNonDefinedSimilarityFactory, SpellPossibilityIteratorTest, HdfsChaosMonkeySafeLeaderTest, TestRawTransformer, PreAnalyzedUpdateProcessorTest, AnalysisErrorHandlingTest, TestTrieFacet, TermsComponentTest, ShardRoutingCustomTest, DistributedExpandComponentTest, AssignTest, TestQuerySenderListener, TestLFUCache, TestNRTOpen, TestExceedMaxTermLength, SolrPluginUtilsTest, TestImplicitCoreProperties, BasicFunctionalityTest, SolrIndexConfigTest, TestSchemaManager, HLLUtilTest, DistributedQueueTest, TestFieldCollectionResource, QueryResultKeyTest, TestJettySolrRunner, TestBinaryResponseWriter, TestSearcherReuse, SpatialFilterTest, TestCodecSupport, OutOfBoxZkACLAndCredentialsProvidersTest, TestSolrCloudWithKerberosAlt, ExplicitHLLTest, CoreAdminRequestStatusTest, TestFileDictionaryLookup, TestSolr4Spatial2, TestSolrDeletionPolicy2, NotRequiredUniqueKeyTest, TestFastLRUCache, HdfsRecoverLeaseTest, BadCopyFieldTest, TestSolrCoreProperties, SpellingQueryConverterTest, DistributedMLTComponentTest, TestQueryUtils, HdfsBasicDistributedZk2Test, ConvertedLegacyTest, ChaosMonkeySafeLeaderTest, UpdateRequestProcessorFactoryTest, TestManagedResource, BasicDistributedZkTest, ShardRoutingTest, TestRandomFaceting, ZkSolrClientTest, TestZkChroot, AutoCommitTest, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, HardAutoCommitTest, TestRangeQuery, TestCoreContainer, StatsComponentTest, QueryElevationComponentTest, BadIndexSchemaTest, TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=75550D481A1BB61D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pt_BR -Dtests.timezone=Canada/Yukon -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestLazyCores (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50
[junit4] > at __randomizedtesting.SeedInfo.seed([75550D481A1BB61D]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:453)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:225)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores:
[junit4] > 1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([75550D481A1BB61D]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=8392, name=searcherExecutor-3908-thread-1, state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([75550D481A1BB61D]:0)
[junit4] Completed [411/555 (1!)] on J1 in 146.80s, 7 tests, 1 failure, 2 errors <<< FAILURES!
[...truncated 458 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:794: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:738: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:526: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1456: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1010: There were test failures: 555 suites (7 ignored), 2206 tests, 3 suite-level errors, 462 ignored (34 assumptions) [seed: 75550D481A1BB61D]
Total time: 44 minutes 28 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any