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/03 18:46:16 UTC
[JENKINS] Lucene-Solr-5.x-MacOSX (64bit/jdk1.7.0) - Build # 2857 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2857/
Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
[/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-004/./collection1/data, /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-004/./collection1/data/index.20151203175356019, /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-004/./collection1/data/index.20151203175355740] expected:<2> but was:<3>
Stack Trace:
java.lang.AssertionError: [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-004/./collection1/data, /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-004/./collection1/data/index.20151203175356019, /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-004/./collection1/data/index.20151203175355740] expected:<2> but was:<3>
at __randomizedtesting.SeedInfo.seed([A8B64B9CC5454C86:5FC5A5C403ADE360]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:815)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1245)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
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$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:745)
Build Log:
[...truncated 9787 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/init-core-data-001
[junit4] 2> 135438 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRateLimitedReplication
[junit4] 2> 135439 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1
[junit4] 2> 135455 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 135463 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2111dbcb{/solr,null,AVAILABLE}
[junit4] 2> 135467 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1b74fd63{HTTP/1.1}{127.0.0.1:57233}
[junit4] 2> 135468 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.Server Started @139361ms
[junit4] 2> 135468 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=57233}
[junit4] 2> 135468 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@265f00f9
[junit4] 2> 135469 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001'
[junit4] 2> 135469 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 135469 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 135470 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/solr.xml
[junit4] 2> 135497 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/.
[junit4] 2> 135498 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer New CoreContainer 276277299
[junit4] 2> 135498 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001]
[junit4] 2> 135498 WARN (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/lib
[junit4] 2> 135499 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] 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> 135501 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 135502 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 135502 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 135502 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 135502 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 135504 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/.
[junit4] 2> 135506 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, loadOnStartup=true, schema=schema.xml, transient=false, dataDir=data/, configSetProperties=configsetprops.json, config=solrconfig.xml}
[junit4] 2> 135506 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1
[junit4] 2> 135507 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 135508 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 135508 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1'
[junit4] 2> 135508 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 135509 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 135509 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 135523 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 135537 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 135564 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 135577 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 135585 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 135590 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1'
[junit4] 2> 135590 INFO (coreLoadExecutor-310-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1
[junit4] 2> 135590 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 135591 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1], dataDir=[null]
[junit4] 2> 135591 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 135591 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data
[junit4] 2> 135591 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 135591 WARN (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 135591 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index
[junit4] 2> 135592 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 135593 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4f87b194 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@559b9456),segFN=segments_1,generation=1}
[junit4] 2> 135594 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 135596 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 135598 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/
[junit4] 2> 135603 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 135604 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 135606 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 135607 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 135618 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 135619 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /config,/admin/system,/admin/properties,/admin/threads,/update/json/docs,/admin/segments,/admin/file,standard,/update/csv,lazy,/replication,/schema,/admin/logging,/admin/mbeans,/admin/ping,/admin/luke,/update/json,/admin/plugins,/update,defaults,/get
[junit4] 2> 135620 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 135620 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 135620 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 135621 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1055375736, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 135623 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4f87b194 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@559b9456),segFN=segments_1,generation=1}
[junit4] 2> 135623 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 135623 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4c23c135[collection1] main
[junit4] 2> 135624 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/conf
[junit4] 2> 135624 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/conf}
[junit4] 2> 135624 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/conf
[junit4] 2> 135624 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 135625 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 135625 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 135625 INFO (coreLoadExecutor-310-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 135625 INFO (searcherExecutor-311-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c23c135[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 136011 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1
[junit4] 2> 136019 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 136024 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c3a0a7a{/solr,null,AVAILABLE}
[junit4] 2> 136024 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3685d5db{HTTP/1.1}{127.0.0.1:57235}
[junit4] 2> 136025 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.Server Started @139917ms
[junit4] 2> 136025 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=57235, hostContext=/solr, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1/data}
[junit4] 2> 136026 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@265f00f9
[junit4] 2> 136026 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002'
[junit4] 2> 136026 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 136027 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 136027 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/solr.xml
[junit4] 2> 136097 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/.
[junit4] 2> 136097 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer New CoreContainer 814051860
[junit4] 2> 136097 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002]
[junit4] 2> 136098 WARN (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/lib
[junit4] 2> 136099 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] 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> 136102 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 136103 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 136103 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 136103 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 136103 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 136105 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/.
[junit4] 2> 136107 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=false, loadOnStartup=true, dataDir=data/, config=solrconfig.xml, name=collection1, schema=schema.xml, configSetProperties=configsetprops.json}
[junit4] 2> 136108 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1
[junit4] 2> 136108 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 136111 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1'
[junit4] 2> 136112 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 136112 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 136112 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 136112 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 136132 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 136161 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 136201 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 136206 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 136219 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 136222 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1'
[junit4] 2> 136222 INFO (coreLoadExecutor-320-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1
[junit4] 2> 136222 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 136223 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1], dataDir=[null]
[junit4] 2> 136223 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 136223 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data
[junit4] 2> 136224 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 136224 WARN (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 136224 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data/index
[junit4] 2> 136224 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 136225 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1a0736da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4fa22f8f),segFN=segments_1,generation=1}
[junit4] 2> 136225 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 136226 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data/
[junit4] 2> 136227 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 136235 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136236 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136237 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136238 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136241 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 136241 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /config,/admin/system,/admin/properties,/admin/threads,/update/json/docs,/admin/segments,/admin/file,standard,/update/csv,lazy,/replication,/schema,/admin/logging,/admin/mbeans,/admin/ping,/admin/luke,/update/json,/admin/plugins,/update,defaults,/get
[junit4] 2> 136243 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 136243 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 136243 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 136244 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1055375736, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 136245 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1a0736da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4fa22f8f),segFN=segments_1,generation=1}
[junit4] 2> 136245 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 136245 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7f9422df[collection1] main
[junit4] 2> 136246 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1/conf
[junit4] 2> 136246 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1/conf}
[junit4] 2> 136246 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/collection1/conf
[junit4] 2> 136246 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 136249 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 136250 INFO (coreLoadExecutor-320-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 136250 INFO (searcherExecutor-321-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7f9422df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 136256 INFO (qtp1438710700-767) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={version=2&qt=/replication&command=indexversion&wt=javabin} status=0 QTime=0
[junit4] 2> 136617 INFO (qtp1438710700-768) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 136618 INFO (qtp1438710700-768) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:*} 0 1
[junit4] 2> 136623 INFO (qtp1183413444-785) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 136624 INFO (qtp1183413444-785) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:*} 0 1
[junit4] 2> 136628 INFO (qtp1438710700-769) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 136630 INFO (qtp1438710700-769) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4f87b194 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@559b9456),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4f87b194 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@559b9456),segFN=segments_2,generation=2}
[junit4] 2> 136630 INFO (qtp1438710700-769) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 136631 INFO (qtp1438710700-769) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1d6da5b1[collection1] main
[junit4] 2> 136631 INFO (qtp1438710700-769) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 136632 INFO (searcherExecutor-311-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1d6da5b1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 136632 INFO (qtp1438710700-769) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin&waitSearcher=true&softCommit=false&commit=true} {commit=} 0 4
[junit4] 2> 136635 INFO (qtp1183413444-786) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 136637 INFO (qtp1183413444-786) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1a0736da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4fa22f8f),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1a0736da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4fa22f8f),segFN=segments_2,generation=2}
[junit4] 2> 136637 INFO (qtp1183413444-786) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 136638 INFO (qtp1183413444-786) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@261e70ac[collection1] main
[junit4] 2> 136638 INFO (qtp1183413444-786) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 136638 INFO (searcherExecutor-321-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@261e70ac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 136639 INFO (qtp1183413444-786) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin&waitSearcher=true&softCommit=false&commit=true} {commit=} 0 3
[junit4] 2> 136642 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1b74fd63{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 136642 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=276277299
[junit4] 2> 136642 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@72c61bc6
[junit4] 2> 136643 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 136644 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 136644 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 136644 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 136645 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 136645 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 136645 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data;done=false>>]
[junit4] 2> 136645 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data
[junit4] 2> 136645 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index;done=false>>]
[junit4] 2> 136645 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index
[junit4] 2> 136646 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2111dbcb{/solr,null,UNAVAILABLE}
[junit4] 2> 136650 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@3685d5db{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 136650 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=814051860
[junit4] 2> 136651 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@65cff094
[junit4] 2> 136652 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 136652 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 136652 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 136652 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 136653 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 136653 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 136654 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 136654 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data/index
[junit4] 2> 136654 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 136654 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-002/./collection1/data
[junit4] 2> 136655 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c3a0a7a{/solr,null,UNAVAILABLE}
[junit4] 2> 136661 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 136665 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@186d940e{/solr,null,AVAILABLE}
[junit4] 2> 136666 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.ServerConnector Started ServerConnector@42132fa9{HTTP/1.1}{127.0.0.1:57241}
[junit4] 2> 136666 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.Server Started @140559ms
[junit4] 2> 136666 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57241, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data}
[junit4] 2> 136667 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@265f00f9
[junit4] 2> 136667 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001'
[junit4] 2> 136667 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 136668 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 136668 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/solr.xml
[junit4] 2> 136694 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/.
[junit4] 2> 136694 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer New CoreContainer 391747437
[junit4] 2> 136694 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001]
[junit4] 2> 136694 WARN (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/lib
[junit4] 2> 136699 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] 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> 136701 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 136701 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 136701 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 136701 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 136702 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 136703 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/.
[junit4] 2> 136705 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=configsetprops.json, name=collection1, transient=false, dataDir=data/, schema=schema.xml, loadOnStartup=true, config=solrconfig.xml}
[junit4] 2> 136706 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1
[junit4] 2> 136706 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 136707 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 136708 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 136708 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1'
[junit4] 2> 136708 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 136708 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 136721 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 136742 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 136777 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 136784 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 136801 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 136803 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1'
[junit4] 2> 136803 INFO (coreLoadExecutor-331-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1
[junit4] 2> 136803 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.NRTCachingDirectoryFactory
[junit4] 2> 136803 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1], dataDir=[null]
[junit4] 2> 136803 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 136804 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data
[junit4] 2> 136804 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 136805 WARN (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 136806 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data/index
[junit4] 2> 136806 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 136824 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb1d27; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 136824 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 136825 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/./collection1/data/
[junit4] 2> 136828 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 136830 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136832 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136833 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136835 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 136838 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 136838 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /config,/admin/system,/admin/properties,/admin/threads,/update/json/docs,/admin/segments,/admin/file,standard,/update/csv,lazy,/replication,/schema,/admin/logging,/admin/mbeans,/admin/ping,/admin/luke,/update/json,/admin/plugins,/update,defaults,/get
[junit4] 2> 136840 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 136840 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 136840 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 136841 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=51.76953125, floorSegmentMB=0.4052734375, forceMergeDeletesPctAllowed=19.523380640150133, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 136843 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb1d27; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 136843 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 136843 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2cf4bdd9[collection1] main
[junit4] 2> 136843 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/conf
[junit4] 2> 136844 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/conf}
[junit4] 2> 136844 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/conf
[junit4] 2> 136844 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 136844 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 136844 INFO (coreLoadExecutor-331-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 136844 INFO (searcherExecutor-332-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2cf4bdd9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 137219 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0]} 0 3
[junit4] 2> 137225 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1]} 0 0
[junit4] 2> 137231 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2]} 0 0
[junit4] 2> 137236 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[3]} 0 0
[junit4] 2> 137243 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[4]} 0 0
[junit4] 2> 137253 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[5]} 0 7
[junit4] 2> 137257 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[6]} 0 0
[junit4] 2> 137262 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[7]} 0 1
[junit4] 2> 137267 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[8]} 0 1
[junit4] 2> 137270 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[9]} 0 0
[junit4] 2> 137275 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[10]} 0 2
[junit4] 2> 137280 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[11]} 0 0
[junit4] 2> 137284 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[12]} 0 0
[junit4] 2> 137289 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[13]} 0 0
[junit4] 2> 137293 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[14]} 0 0
[junit4] 2> 137301 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[15]} 0 1
[junit4] 2> 137305 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[16]} 0 0
[junit4] 2> 137309 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[17]} 0 0
[junit4] 2> 137313 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[18]} 0 0
[junit4] 2> 137319 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[19]} 0 1
[junit4] 2> 137327 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[20]} 0 0
[junit4] 2> 137331 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[21]} 0 0
[junit4] 2> 137340 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[22]} 0 0
[junit4] 2> 137344 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[23]} 0 0
[junit4] 2> 137349 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[24]} 0 0
[junit4] 2> 137354 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[25]} 0 1
[junit4] 2> 137359 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[26]} 0 0
[junit4] 2> 137365 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[27]} 0 0
[junit4] 2> 137370 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[28]} 0 1
[junit4] 2> 137374 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[29]} 0 0
[junit4] 2> 137380 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[30]} 0 1
[junit4] 2> 137384 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[31]} 0 0
[junit4] 2> 137389 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[32]} 0 0
[junit4] 2> 137393 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[33]} 0 0
[junit4] 2> 137400 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[34]} 0 0
[junit4] 2> 137405 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[35]} 0 0
[junit4] 2> 137409 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[36]} 0 0
[junit4] 2> 137413 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[37]} 0 0
[junit4] 2> 137416 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[38]} 0 0
[junit4] 2> 137420 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[39]} 0 0
[junit4] 2> 137424 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[40]} 0 0
[junit4] 2> 137429 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[41]} 0 0
[junit4] 2> 137436 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[42]} 0 0
[junit4] 2> 137440 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[43]} 0 0
[junit4] 2> 137444 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[44]} 0 0
[junit4] 2> 137449 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[45]} 0 0
[junit4] 2> 137452 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[46]} 0 0
[junit4] 2> 137456 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[47]} 0 0
[junit4] 2> 137460 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[48]} 0 0
[junit4] 2> 137465 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[49]} 0 0
[junit4] 2> 137469 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[50]} 0 0
[junit4] 2> 137472 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[51]} 0 0
[junit4] 2> 137475 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[52]} 0 0
[junit4] 2> 137480 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[53]} 0 0
[junit4] 2> 137485 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[54]} 0 0
[junit4] 2> 137489 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[55]} 0 0
[junit4] 2> 137493 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[56]} 0 0
[junit4] 2> 137496 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[57]} 0 0
[junit4] 2> 137500 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[58]} 0 0
[junit4] 2> 137503 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[59]} 0 0
[junit4] 2> 137509 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[60]} 0 0
[junit4] 2> 137515 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[61]} 0 0
[junit4] 2> 137518 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[62]} 0 0
[junit4] 2> 137525 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[63]} 0 0
[junit4] 2> 137531 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[64]} 0 0
[junit4] 2> 137535 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[65]} 0 0
[junit4] 2> 137540 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[66]} 0 0
[junit4] 2> 137544 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[67]} 0 0
[junit4] 2> 137547 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[68]} 0 0
[junit4] 2> 137551 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[69]} 0 0
[junit4] 2> 137555 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[70]} 0 0
[junit4] 2> 137558 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[71]} 0 0
[junit4] 2> 137566 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[72]} 0 1
[junit4] 2> 137571 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[73]} 0 0
[junit4] 2> 137574 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[74]} 0 0
[junit4] 2> 137578 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[75]} 0 0
[junit4] 2> 137581 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[76]} 0 0
[junit4] 2> 137585 INFO (qtp1797520007-812) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[77]} 0 0
[junit4] 2> 137589 INFO (qtp1797520007-807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[78]} 0 0
[junit4] 2> 137593 INFO (qtp1797520007-808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[79]} 0 0
[junit4] 2> 137598 INFO (qtp1797520007-809) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[80]} 0 1
[junit4] 2> 137605 INFO (qtp1797520007-810) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[81]} 0 0
[junit4] 2> 137609 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 137998 INFO (qtp1797520007-811) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb1d27; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ceb1d27; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 137999 INFO (qtp1797520007-811) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 138004 INFO (qtp1797520007-811) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@393c5ed0[collection1] main
[junit4] 2> 138004 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 138004 INFO (searcherExecutor-332-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@393c5ed0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C82)))}
[junit4] 2> 138004 INFO (qtp1797520007-811) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={version=2&wt=javabin&waitSearcher=true&softCommit=false&commit=true} {commit=} 0 395
[junit4] 2> 138007 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@42132fa9{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 138007 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=391747437
[junit4] 2> 138007 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@58b7b8f3
[junit4] 2> 138008 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=82,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 138009 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 138009 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 138009 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 138010 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 138011 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 138011 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data;done=false>>]
[junit4] 2> 138011 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data
[junit4] 2> 138011 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-001/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9C
[...truncated too long message...]
dler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7edeb051
[junit4] 2> 221988 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=6,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 221988 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 221988 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 221988 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 221989 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 221989 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 221989 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-026/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-026/./collection1/data;done=false>>]
[junit4] 2> 221989 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-026/./collection1/data
[junit4] 2> 221989 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-026/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-026/./collection1/data/index;done=false>>]
[junit4] 2> 221989 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-026/./collection1/data/index
[junit4] 2> 221990 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@303245bf{/solr,null,UNAVAILABLE}
[junit4] 2> 221992 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@a8b3ad8{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 221992 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=498994205
[junit4] 2> 221993 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@547adcea
[junit4] 2> 221993 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ 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> 221993 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 221993 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 221993 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 221995 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 221995 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 221995 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-027/./collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-027/./collection1/data;done=false>>]
[junit4] 2> 221995 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-027/./collection1/data
[junit4] 2> 221995 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-027/./collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-027/./collection1/data/index;done=false>>]
[junit4] 2> 221995 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001/solr-instance-027/./collection1/data/index
[junit4] 2> 221996 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[A8B64B9CC5454C86]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f2e1596{/solr,null,UNAVAILABLE}
[junit4] 2> 221998 INFO (SUITE-TestReplicationHandler-seed#[A8B64B9CC5454C86]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_A8B64B9CC5454C86-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {newname=FSTOrd50, id=FSTOrd50, name=FSTOrd50}, docValues:{}, sim=DefaultSimilarity, locale=de_LU, timezone=Europe/Monaco
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=3,threads=1,free=76045544,total=149274624
[junit4] 2> NOTE: All tests run in this JVM: [CoreAdminRequestStatusTest, SmileWriterTest, StandardRequestHandlerTest, RemoteQueryErrorTest, OverseerTaskQueueTest, ShardRoutingTest, SimpleFacetsTest, TestPostingsSolrHighlighter, TestPartialUpdateDeduplication, TestCustomSort, IndexBasedSpellCheckerTest, RankQueryTest, AddBlockUpdateTest, TestPseudoReturnFields, HdfsCollectionsAPIDistributedZkTest, TestReplicationHandler]
[junit4] Completed [22/552] on J0 in 86.59s, 13 tests, 1 failure <<< FAILURES!
[...truncated 1676 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:792: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:736: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:233: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:526: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1452: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1006: There were test failures: 552 suites (7 ignored), 2185 tests, 1 failure, 87 ignored (37 assumptions) [seed: A8B64B9CC5454C86]
Total time: 80 minutes 41 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-MacOSX (64bit/jdk1.7.0) - Build # 2858 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2858/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.TestAuthenticationFramework.testBasics
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([C0CC3620413ADA5C]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestAuthenticationFramework
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([C0CC3620413ADA5C]:0)
Build Log:
[...truncated 11054 lines...]
[junit4] Suite: org.apache.solr.cloud.TestAuthenticationFramework
[junit4] 2> 344140 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 344140 INFO (Thread-657) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 344140 INFO (Thread-657) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 344241 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.ZkTestServer start zk server on port:60331
[junit4] 2> 344241 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 344242 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 344251 INFO (zkCallback-1901-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29c98b90 name:ZooKeeperConnection Watcher:127.0.0.1:60331 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 344251 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 344252 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 344252 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 344273 INFO (jetty-launcher-1900-thread-1) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 344274 INFO (jetty-launcher-1900-thread-3) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 344280 INFO (jetty-launcher-1900-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5834393a{/solr,null,AVAILABLE}
[junit4] 2> 344280 INFO (jetty-launcher-1900-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@2db687ad{HTTP/1.1}{127.0.0.1:60333}
[junit4] 2> 344280 INFO (jetty-launcher-1900-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6216bc17{/solr,null,AVAILABLE}
[junit4] 2> 344280 INFO (jetty-launcher-1900-thread-1) [ ] o.e.j.s.Server Started @349501ms
[junit4] 2> 344280 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=60333, hostContext=/solr}
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-3) [ ] o.e.j.s.ServerConnector Started ServerConnector@36254a57{HTTP/1.1}{127.0.0.1:60334}
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-3) [ ] o.e.j.s.Server Started @349502ms
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=60334, hostContext=/solr}
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node1'
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node3'
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 344281 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 344282 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 344282 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 344282 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 344289 INFO (zkCallback-1902-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3aa60538 name:ZooKeeperConnection Watcher:127.0.0.1:60331/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 344289 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 344289 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 344291 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 344292 INFO (zkCallback-1903-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e4d38ea name:ZooKeeperConnection Watcher:127.0.0.1:60331/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 344292 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 344292 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 344293 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 344328 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node1/.
[junit4] 2> 344328 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node3/.
[junit4] 2> 344328 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1994206499
[junit4] 2> 344328 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.CoreContainer New CoreContainer 851349964
[junit4] 2> 344328 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node1]
[junit4] 2> 344328 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node3]
[junit4] 2> 344329 WARN (jetty-launcher-1900-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node1/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node1/lib
[junit4] 2> 344329 WARN (jetty-launcher-1900-thread-3) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node3/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node3/lib
[junit4] 2> 344329 INFO (jetty-launcher-1900-thread-1) [ ] 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> 344329 INFO (jetty-launcher-1900-thread-3) [ ] 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> 344332 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 344332 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 344333 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 344333 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 344333 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 344333 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60331/solr
[junit4] 2> 344333 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 344334 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 344334 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60331/solr
[junit4] 2> 344334 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 344334 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 344334 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 344335 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 344335 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 344339 INFO (zkCallback-1906-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@220668f2 name:ZooKeeperConnection Watcher:127.0.0.1:60331 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 344340 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 344340 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 344342 INFO (zkCallback-1907-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7f7f4a79 name:ZooKeeperConnection Watcher:127.0.0.1:60331 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 344342 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 344342 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 344345 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 344350 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 344352 INFO (zkCallback-1908-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23868524 name:ZooKeeperConnection Watcher:127.0.0.1:60331/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 344352 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 344361 INFO (zkCallback-1909-thread-1-processing-n:127.0.0.1:60333_solr) [n:127.0.0.1:60333_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@65180046 name:ZooKeeperConnection Watcher:127.0.0.1:60331/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 344361 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 344367 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 344367 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 344384 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 344384 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 344467 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 344469 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 344487 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 344487 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 344513 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 344515 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 344540 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 344540 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 344545 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 344546 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 344557 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 344558 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 344564 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 344564 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 344568 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 344569 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 344585 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60334_solr
[junit4] 2> 344585 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60333_solr
[junit4] 2> 344588 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60334_solr
[junit4] 2> 344588 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60333_solr
[junit4] 2> 344608 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 344609 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 344613 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 344615 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 344619 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 344620 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 344622 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94974400824737798-127.0.0.1:60333_solr-n_0000000000
[junit4] 2> 344624 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94974400824737797-127.0.0.1:60334_solr-n_0000000001
[junit4] 2> 344624 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60333_solr
[junit4] 2> 344624 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 344626 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94974400824737798-127.0.0.1:60333_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 344626 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 344631 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.Overseer Overseer (id=94974400824737798-127.0.0.1:60333_solr-n_0000000000) starting
[junit4] 2> 344640 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 344660 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 344660 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin
[junit4] 2> 344662 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.CoreContainer Reconfiguring the shard handler factory and update shard handler.
[junit4] 2> 344662 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@606a47b5
[junit4] 2> 344663 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@606a47b5
[junit4] 2> 344665 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node3/.
[junit4] 2> 344665 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 344669 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 344669 INFO (jetty-launcher-1900-thread-3) [n:127.0.0.1:60334_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 344704 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 344705 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 344705 INFO (OverseerCollectionConfigSetProcessor-94974400824737798-127.0.0.1:60333_solr-n_0000000000) [n:127.0.0.1:60333_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 344706 INFO (OverseerStateUpdate-94974400824737798-127.0.0.1:60333_solr-n_0000000000) [n:127.0.0.1:60333_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 344726 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 344726 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin
[junit4] 2> 344726 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.CoreContainer Reconfiguring the shard handler factory and update shard handler.
[junit4] 2> 344726 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@29413d84
[junit4] 2> 344726 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@29413d84
[junit4] 2> 344728 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-001/node1/.
[junit4] 2> 344728 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 344729 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 344729 INFO (jetty-launcher-1900-thread-1) [n:127.0.0.1:60333_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 344730 INFO (jetty-launcher-1900-thread-3) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@2db687ad{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 344730 INFO (jetty-launcher-1900-thread-1) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@36254a57{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 344731 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1994206499
[junit4] 2> 344731 INFO (jetty-launcher-1900-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=851349964
[junit4] 2> 344733 INFO (jetty-launcher-1900-thread-3) [ ] o.a.s.c.Overseer Overseer (id=94974400824737798-127.0.0.1:60333_solr-n_0000000000) closing
[junit4] 2> 344733 INFO (OverseerStateUpdate-94974400824737798-127.0.0.1:60333_solr-n_0000000000) [n:127.0.0.1:60333_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:60333_solr
[junit4] 2> 344736 INFO (zkCallback-1909-thread-1-processing-n:127.0.0.1:60333_solr) [n:127.0.0.1:60333_solr ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 344736 INFO (zkCallback-1908-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 344737 WARN (zkCallback-1908-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 344738 INFO (jetty-launcher-1900-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6216bc17{/solr,null,UNAVAILABLE}
[junit4] 2> 346237 WARN (zkCallback-1909-thread-1-processing-n:127.0.0.1:60333_solr) [n:127.0.0.1:60333_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 346239 INFO (jetty-launcher-1900-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5834393a{/solr,null,UNAVAILABLE}
[junit4] 2> 346241 INFO (TEST-TestAuthenticationFramework.testErrorsInStartup-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60331 60331
[junit4] 2> 346336 INFO (Thread-657) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60331 60331
[junit4] 2> 347990 WARN (Thread-657) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/collections
[junit4] 2> 2 /solr/live_nodes
[junit4] 2>
[junit4] 2> 347995 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 347995 INFO (Thread-679) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 347996 INFO (Thread-679) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 348096 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.ZkTestServer start zk server on port:60345
[junit4] 2> 348097 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348097 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348283 INFO (zkCallback-1911-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6fb67973 name:ZooKeeperConnection Watcher:127.0.0.1:60345 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348283 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348283 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348283 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 348369 INFO (jetty-launcher-1910-thread-1) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 348371 INFO (jetty-launcher-1910-thread-3) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 348372 INFO (jetty-launcher-1910-thread-2) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 348373 INFO (jetty-launcher-1910-thread-4) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 348373 INFO (jetty-launcher-1910-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@592cca38{/solr,null,AVAILABLE}
[junit4] 2> 348373 INFO (jetty-launcher-1910-thread-5) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 348374 INFO (jetty-launcher-1910-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@40083e81{HTTP/1.1}{127.0.0.1:60347}
[junit4] 2> 348374 INFO (jetty-launcher-1910-thread-1) [ ] o.e.j.s.Server Started @353595ms
[junit4] 2> 348374 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=60347, hostContext=/solr}
[junit4] 2> 348374 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 348374 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node1'
[junit4] 2> 348374 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 348374 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 348375 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348375 INFO (jetty-launcher-1910-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@39a16a97{/solr,null,AVAILABLE}
[junit4] 2> 348376 INFO (jetty-launcher-1910-thread-5) [ ] o.e.j.s.ServerConnector Started ServerConnector@b08415b{HTTP/1.1}{127.0.0.1:60348}
[junit4] 2> 348376 INFO (jetty-launcher-1910-thread-5) [ ] o.e.j.s.Server Started @353597ms
[junit4] 2> 348376 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=60348, hostContext=/solr}
[junit4] 2> 348376 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 348376 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node5'
[junit4] 2> 348376 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 348376 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 348377 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348379 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348380 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348380 INFO (jetty-launcher-1910-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75441c31{/solr,null,AVAILABLE}
[junit4] 2> 348381 INFO (jetty-launcher-1910-thread-4) [ ] o.e.j.s.ServerConnector Started ServerConnector@3e46b86{HTTP/1.1}{127.0.0.1:60351}
[junit4] 2> 348381 INFO (jetty-launcher-1910-thread-4) [ ] o.e.j.s.Server Started @353602ms
[junit4] 2> 348381 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60351}
[junit4] 2> 348381 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 348381 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node4'
[junit4] 2> 348381 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 348381 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 348382 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348382 INFO (jetty-launcher-1910-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27833e30{/solr,null,AVAILABLE}
[junit4] 2> 348383 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348384 INFO (jetty-launcher-1910-thread-3) [ ] o.e.j.s.ServerConnector Started ServerConnector@322b5617{HTTP/1.1}{127.0.0.1:60352}
[junit4] 2> 348384 INFO (jetty-launcher-1910-thread-3) [ ] o.e.j.s.Server Started @353605ms
[junit4] 2> 348384 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=60352, hostContext=/solr}
[junit4] 2> 348384 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 348384 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node3'
[junit4] 2> 348384 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 348384 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 348385 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348387 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348388 INFO (jetty-launcher-1910-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4be99284{/solr,null,AVAILABLE}
[junit4] 2> 348388 INFO (jetty-launcher-1910-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@53923f0b{HTTP/1.1}{127.0.0.1:60355}
[junit4] 2> 348388 INFO (jetty-launcher-1910-thread-2) [ ] o.e.j.s.Server Started @353609ms
[junit4] 2> 348388 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60355}
[junit4] 2> 348388 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 348388 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node2'
[junit4] 2> 348389 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 348389 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 348389 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348389 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348397 INFO (zkCallback-1912-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@442bf62a name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348397 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348397 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348408 INFO (zkCallback-1913-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@240b6998 name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348408 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348409 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 348409 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348409 INFO (zkCallback-1914-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f935754 name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348409 INFO (zkCallback-1916-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@24a5bfe name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348409 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348409 INFO (zkCallback-1915-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7425eeb8 name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348409 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348410 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 348410 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348410 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348409 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348410 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348411 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 348411 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 348411 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 348461 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node3/.
[junit4] 2> 348461 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node5/.
[junit4] 2> 348461 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.CoreContainer New CoreContainer 1486146447
[junit4] 2> 348461 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.CoreContainer New CoreContainer 281036472
[junit4] 2> 348462 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node3]
[junit4] 2> 348462 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node5]
[junit4] 2> 348462 WARN (jetty-launcher-1910-thread-3) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node3/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node3/lib
[junit4] 2> 348463 WARN (jetty-launcher-1910-thread-5) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node5/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node5/lib
[junit4] 2> 348463 INFO (jetty-launcher-1910-thread-3) [ ] 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> 348463 INFO (jetty-launcher-1910-thread-5) [ ] 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> 348462 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node2/.
[junit4] 2> 348463 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 2057505718
[junit4] 2> 348463 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node2]
[junit4] 2> 348463 WARN (jetty-launcher-1910-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node2/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node2/lib
[junit4] 2> 348463 INFO (jetty-launcher-1910-thread-2) [ ] 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> 348463 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60345/solr
[junit4] 2> 348464 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 348465 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 348465 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 348465 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348465 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60345/solr
[junit4] 2> 348465 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60345/solr
[junit4] 2> 348466 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 348466 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348465 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 348466 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348466 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348467 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348467 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348487 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node4/.
[junit4] 2> 348487 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.CoreContainer New CoreContainer 1048126433
[junit4] 2> 348487 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node4]
[junit4] 2> 348487 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node1/.
[junit4] 2> 348487 WARN (jetty-launcher-1910-thread-4) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node4/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node4/lib
[junit4] 2> 348488 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1649061992
[junit4] 2> 348488 INFO (jetty-launcher-1910-thread-4) [ ] 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> 348488 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node1]
[junit4] 2> 348488 WARN (jetty-launcher-1910-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node1/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node1/lib
[junit4] 2> 348490 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 348490 INFO (jetty-launcher-1910-thread-1) [ ] 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> 348490 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 348490 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 348490 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60345/solr
[junit4] 2> 348490 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 348490 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348491 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 348491 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348492 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 348492 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 348492 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60345/solr
[junit4] 2> 348492 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 348492 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 348492 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348519 INFO (zkCallback-1920-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f4a0482 name:ZooKeeperConnection Watcher:127.0.0.1:60345 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348519 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348519 INFO (zkCallback-1921-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6514603c name:ZooKeeperConnection Watcher:127.0.0.1:60345 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348519 INFO (jetty-launcher-1910-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348519 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348519 INFO (zkCallback-1922-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56fe920 name:ZooKeeperConnection Watcher:127.0.0.1:60345 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348520 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348520 INFO (jetty-launcher-1910-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348520 INFO (jetty-launcher-1910-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348542 INFO (zkCallback-1924-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@371ee2ce name:ZooKeeperConnection Watcher:127.0.0.1:60345 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348542 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348542 INFO (zkCallback-1926-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62ea4d4c name:ZooKeeperConnection Watcher:127.0.0.1:60345 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348542 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348542 INFO (jetty-launcher-1910-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348543 INFO (jetty-launcher-1910-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 348567 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1516ab6eefa0008, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 348568 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348569 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348569 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348592 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348592 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 348620 INFO (zkCallback-1927-thread-1-processing-n:127.0.0.1:60348_solr) [n:127.0.0.1:60348_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@686bbb61 name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348620 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348620 INFO (zkCallback-1928-thread-1-processing-n:127.0.0.1:60355_solr) [n:127.0.0.1:60355_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@53eecd15 name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348620 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348620 INFO (zkCallback-1929-thread-1-processing-n:127.0.0.1:60352_solr) [n:127.0.0.1:60352_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f228e07 name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348621 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348650 INFO (zkCallback-1930-thread-1-processing-n:127.0.0.1:60351_solr) [n:127.0.0.1:60351_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e04db9e name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348650 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348650 INFO (zkCallback-1931-thread-1-processing-n:127.0.0.1:60347_solr) [n:127.0.0.1:60347_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7a90f3df name:ZooKeeperConnection Watcher:127.0.0.1:60345/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 348650 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 348704 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 348704 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 348704 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 348705 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 348705 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 348815 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 348815 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 348815 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 348847 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 348848 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 349078 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 349078 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 349078 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 349079 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 349079 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 349168 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 349168 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 349168 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 349169 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 349169 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 349223 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 349223 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 349223 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 349223 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 349224 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 349274 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 349276 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 349276 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 349276 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 349288 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 349318 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 349318 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 349319 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 349357 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 349358 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 349372 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 349372 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 349435 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 349435 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 349435 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 349454 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 349455 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 349491 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 349501 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 349501 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 349501 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 349502 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 349523 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60348_solr
[junit4] 2> 349523 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60347_solr
[junit4] 2> 349523 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60351_solr
[junit4] 2> 349523 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60355_solr
[junit4] 2> 349527 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60352_solr
[junit4] 2> 349527 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60347_solr
[junit4] 2> 349527 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60348_solr
[junit4] 2> 349537 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60351_solr
[junit4] 2> 349537 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60355_solr
[junit4] 2> 349547 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60352_solr
[junit4] 2> 349583 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 349583 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 349583 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 349584 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 349584 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 349613 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 349613 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 349614 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 349614 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 349614 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 349660 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 349660 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 349660 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 349664 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 349664 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94974401077444621-127.0.0.1:60352_solr-n_0000000000
[junit4] 2> 349675 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 349675 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94974401077444623-127.0.0.1:60347_solr-n_0000000001
[junit4] 2> 349675 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94974401077444620-127.0.0.1:60355_solr-n_0000000002
[junit4] 2> 349675 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60352_solr
[junit4] 2> 349675 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 349696 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94974401077444622-127.0.0.1:60351_solr-n_0000000003
[junit4] 2> 349707 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94974401077444619-127.0.0.1:60348_solr-n_0000000004
[junit4] 2> 349707 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94974401077444621-127.0.0.1:60352_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 349707 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 349708 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94974401077444623-127.0.0.1:60347_solr-n_0000000001 to know if I could be the leader
[junit4] 2> 349708 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94974401077444620-127.0.0.1:60355_solr-n_0000000002 to know if I could be the leader
[junit4] 2> 349708 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 349708 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 349708 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94974401077444622-127.0.0.1:60351_solr-n_0000000003 to know if I could be the leader
[junit4] 2> 349708 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 349718 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.Overseer Overseer (id=94974401077444621-127.0.0.1:60352_solr-n_0000000000) starting
[junit4] 2> 349787 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.CoreContainer Reconfiguring the shard handler factory and update shard handler.
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.CoreContainer Reconfiguring the shard handler factory and update shard handler.
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@54df5e4
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@414a4e74
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@54df5e4
[junit4] 2> 349802 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@414a4e74
[junit4] 2> 349805 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 349805 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin
[junit4] 2> 349805 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.CoreContainer Reconfiguring the shard handler factory and update shard handler.
[junit4] 2> 349805 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@7564c328
[junit4] 2> 349805 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node5/.
[junit4] 2> 349805 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@7564c328
[junit4] 2> 349805 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 349807 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node1/.
[junit4] 2> 349808 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node2/.
[junit4] 2> 349808 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 349808 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 349809 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 349809 INFO (jetty-launcher-1910-thread-5) [n:127.0.0.1:60348_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 349809 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 349810 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin
[junit4] 2> 349810 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.CoreContainer Reconfiguring the shard handler factory and update shard handler.
[junit4] 2> 349810 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@6db56d83
[junit4] 2> 349810 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 349810 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@6db56d83
[junit4] 2> 349810 INFO (jetty-launcher-1910-thread-2) [n:127.0.0.1:60355_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 349811 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node4/.
[junit4] 2> 349811 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 349818 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 349818 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 349818 INFO (jetty-launcher-1910-thread-4) [n:127.0.0.1:60351_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 349818 INFO (jetty-launcher-1910-thread-1) [n:127.0.0.1:60347_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 349892 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 349893 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 349893 INFO (OverseerCollectionConfigSetProcessor-94974401077444621-127.0.0.1:60352_solr-n_0000000000) [n:127.0.0.1:60352_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 349894 INFO (OverseerStateUpdate-94974401077444621-127.0.0.1:60352_solr-n_0000000000) [n:127.0.0.1:60352_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 349912 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 349912 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin
[junit4] 2> 349912 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.CoreContainer Reconfiguring the shard handler factory and update shard handler.
[junit4] 2> 349912 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@48088643
[junit4] 2> 349912 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.cloud.TestAuthenticationFramework$MockAuthenticationPlugin$MockClientConfigurer@48088643
[junit4] 2> 349913 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001/tempDir-002/node3/.
[junit4] 2> 349913 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 349915 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 349915 INFO (jetty-launcher-1910-thread-3) [n:127.0.0.1:60352_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 349915 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 349916 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 349924 INFO (zkCallback-1932-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15235f47 name:ZooKeeperConnection Watcher:127.0.0.1:60345 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 349924 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 349924 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 349941 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.TestMiniSolrCloudCluster #### Stopping a server
[junit4] 2> 349941 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@b08415b{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 349942 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=281036472
[junit4] 2> 349953 INFO (zkCallback-1928-thread-1-processing-n:127.0.0.1:60355_solr) [n:127.0.0.1:60355_solr ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 349953 ERROR (jetty-launcher-1910-thread-5-EventThread) [n:127.0.0.1:60348_solr ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1@5cc7ffe8 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4e7207ee[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 1]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:215)
[junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:110)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:261)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> 349954 INFO (zkCallback-1931-thread-1-processing-n:127.0.0.1:60347_solr) [n:127.0.0.1:60347_solr ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 349955 INFO (TEST-TestAuthenticationFramework.testCollectionCreateSearchDelete-seed#[C0CC3620413ADA5C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@39a16a97{/solr,null,UNAVAILABLE}
[junit4] 2> 349956 INFO (zkCallback-1930-thread-1-processing-n:127.0.0.1:60351_solr) [n:127.0.0.1:60351_solr ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live
[...truncated too long message...]
t org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:308)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getClusterProps(ZkStateReader.java:731)
[junit4] 2> ... 3 more
[junit4] 2>
[junit4] 2> 7564421 ERROR (TEST-TestAuthenticationFramework.testBasics-seed#[C0CC3620413ADA5C]-EventThread) [n:127.0.0.1:60624_solr ] o.a.z.ClientCnxn Event thread exiting due to interruption
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2052)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
[junit4] 2> 7564422 INFO (jetty-launcher-2039-thread-5) [ ] o.a.s.c.Overseer Overseer (id=94974407962132499-127.0.0.1:60624_solr-n_0000000005) closing
[junit4] 2> 7564421 WARN (TEST-TestAuthenticationFramework.testBasics-seed#[C0CC3620413ADA5C]-SendThread(127.0.0.1:60599)) [n:127.0.0.1:60624_solr ] o.a.z.ClientCnxn Session 0x1516ab889560013 for server 127.0.0.1/127.0.0.1:60599, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.nio.channels.ClosedByInterruptException
[junit4] 2> at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:501)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:117)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:366)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 7564422 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x1516ab889560013 due to java.nio.channels.ClosedByInterruptException
[junit4] 2> 7564426 INFO (jetty-launcher-2039-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@38d2cd9f{/solr,null,UNAVAILABLE}
[junit4] 2> 7564426 WARN (jetty-launcher-2039-thread-5) [ ] o.e.j.u.c.AbstractLifeCycle FAILED qtp567246772{FAILED,8<=8<=10000,i=4,q=8}: java.lang.InterruptedException
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Thread.join(Thread.java:1289)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.doStop(QueuedThreadPool.java:138)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:143)
[junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:162)
[junit4] 2> at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
[junit4] 2> at org.eclipse.jetty.server.Server.doStop(Server.java:456)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.stop(JettySolrRunner.java:441)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.stopJettySolrRunner(MiniSolrCloudCluster.java:363)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster$2.call(MiniSolrCloudCluster.java:431)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster$2.call(MiniSolrCloudCluster.java:428)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:232)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 7564428 WARN (jetty-launcher-2039-thread-5) [ ] o.e.j.u.c.AbstractLifeCycle FAILED org.eclipse.jetty.server.Server@36a25f80: java.lang.InterruptedException
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Thread.join(Thread.java:1289)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.doStop(QueuedThreadPool.java:138)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:143)
[junit4] 2> at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:162)
[junit4] 2> at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
[junit4] 2> at org.eclipse.jetty.server.Server.doStop(Server.java:456)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.stop(JettySolrRunner.java:441)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.stopJettySolrRunner(MiniSolrCloudCluster.java:363)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster$2.call(MiniSolrCloudCluster.java:431)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster$2.call(MiniSolrCloudCluster.java:428)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:232)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 7564428 INFO (TEST-TestAuthenticationFramework.testBasics-seed#[C0CC3620413ADA5C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60599 60599
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestAuthenticationFramework_C0CC3620413ADA5C-001
[junit4] 2> Dec 04, 2015 3:59:30 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {_version_=Lucene50(blocksize=128), range_facet_l_dv=FSTOrd50, intDefault=Lucene50(blocksize=128), range_facet_i_dv=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=Asserting), timestamp=PostingsFormat(name=Asserting), range_facet_l=PostingsFormat(name=Asserting), id=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=uk, timezone=PST8PDT
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=3,threads=1,free=104606416,total=303976448
[junit4] 2> NOTE: All tests run in this JVM: [OverseerStatusTest, SharedFSAutoReplicaFailoverUtilsTest, TestFunctionQuery, TestCloudInspectUtil, TestMergePolicyConfig, TestConfigSetsAPIExclusivity, ClusterStateUpdateTest, SyncSliceTest, TestElisionMultitermQuery, OverseerCollectionConfigSetProcessorTest, TestSurroundQueryParser, DOMUtilTest, TestSolrConfigHandlerCloud, TestReRankQParserPlugin, TestRandomDVFaceting, TestSolrDynamicMBean, TestSort, TestHdfsUpdateLog, DeleteInactiveReplicaTest, StatelessScriptUpdateProcessorFactoryTest, HdfsNNFailoverTest, TestJmxIntegration, HdfsCollectionsAPIDistributedZkTest, SimplePostToolTest, TestStressLucene, TestLFUCache, TestAuthenticationFramework]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestAuthenticationFramework -Dtests.seed=C0CC3620413ADA5C -Dtests.slow=true -Dtests.locale=uk -Dtests.timezone=PST8PDT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestAuthenticationFramework (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([C0CC3620413ADA5C]:0)
[junit4] Completed [441/552] on J0 in 7221.53s, 5 tests, 2 errors <<< FAILURES!
[...truncated 364 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:792: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:736: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:233: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:526: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1452: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1006: There were test failures: 552 suites (7 ignored), 2183 tests, 1 suite-level error, 1 error, 87 ignored (37 assumptions) [seed: C0CC3620413ADA5C]
Total time: 182 minutes 10 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