lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.7.0_55) - Build # 10320 - Failure!
Date Sun, 01 Jun 2014 01:52:38 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/10320/
Java: 64bit/jdk1.7.0_55 -XX:+UseCompressedOops -XX:+UseG1GC

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.SSLMigrationTest

Error Message:
9 threads leaked from SUITE scope at org.apache.solr.cloud.SSLMigrationTest:     1) Thread[id=2736, name=qtp1954295020-2736, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)    2) Thread[id=2739, name=qtp1954295020-2739, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)    3) Thread[id=2735, name=qtp1954295020-2735, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)    4) Thread[id=2740, name=qtp1954295020-2740, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)    5) Thread[id=2734, name=HashSessionScavenger-99, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at java.lang.Object.wait(Native Method)         at java.util.TimerThread.mainLoop(Timer.java:552)         at java.util.TimerThread.run(Timer.java:505)    6) Thread[id=2737, name=qtp1954295020-2737, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)    7) Thread[id=2741, name=qtp1954295020-2741, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)    8) Thread[id=2738, name=qtp1954295020-2738, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)    9) Thread[id=2742, name=qtp1954295020-2742, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)         at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 9 threads leaked from SUITE scope at org.apache.solr.cloud.SSLMigrationTest: 
   1) Thread[id=2736, name=qtp1954295020-2736, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=2739, name=qtp1954295020-2739, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
   3) Thread[id=2735, name=qtp1954295020-2735, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
   4) Thread[id=2740, name=qtp1954295020-2740, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
   5) Thread[id=2734, name=HashSessionScavenger-99, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at java.lang.Object.wait(Native Method)
        at java.util.TimerThread.mainLoop(Timer.java:552)
        at java.util.TimerThread.run(Timer.java:505)
   6) Thread[id=2737, name=qtp1954295020-2737, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
   7) Thread[id=2741, name=qtp1954295020-2741, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
   8) Thread[id=2738, name=qtp1954295020-2738, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
   9) Thread[id=2742, name=qtp1954295020-2742, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
        at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
        at java.lang.Thread.run(Thread.java:745)
	at __randomizedtesting.SeedInfo.seed([BA01CF3B0461BD72]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.SSLMigrationTest

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=2734, name=HashSessionScavenger-99, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]         at java.lang.Object.wait(Native Method)         at java.util.TimerThread.mainLoop(Timer.java:552)         at java.util.TimerThread.run(Timer.java:505)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   1) Thread[id=2734, name=HashSessionScavenger-99, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
        at java.lang.Object.wait(Native Method)
        at java.util.TimerThread.mainLoop(Timer.java:552)
        at java.util.TimerThread.run(Timer.java:505)
	at __randomizedtesting.SeedInfo.seed([BA01CF3B0461BD72]:0)


FAILED:  org.apache.solr.cloud.SSLMigrationTest.testDistribSearch

Error Message:
Address already in use

Stack Trace:
java.net.BindException: Address already in use
	at __randomizedtesting.SeedInfo.seed([BA01CF3B0461BD72:3BE74123733EDD4E]:0)
	at sun.nio.ch.Net.bind0(Native Method)
	at sun.nio.ch.Net.bind(Net.java:444)
	at sun.nio.ch.Net.bind(Net.java:436)
	at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:214)
	at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
	at org.eclipse.jetty.server.nio.SelectChannelConnector.open(SelectChannelConnector.java:187)
	at org.eclipse.jetty.server.AbstractConnector.doStart(AbstractConnector.java:316)
	at org.eclipse.jetty.server.nio.SelectChannelConnector.doStart(SelectChannelConnector.java:265)
	at org.eclipse.jetty.server.ssl.SslSelectChannelConnector.doStart(SslSelectChannelConnector.java:631)
	at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:64)
	at org.eclipse.jetty.server.Server.doStart(Server.java:291)
	at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:64)
	at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:432)
	at org.apache.solr.cloud.SSLMigrationTest.testMigrateSSL(SSLMigrationTest.java:76)
	at org.apache.solr.cloud.SSLMigrationTest.doTest(SSLMigrationTest.java:57)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
	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:1618)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11176 lines...]
   [junit4] Suite: org.apache.solr.cloud.SSLMigrationTest
   [junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/init-core-data-001
   [junit4]   2> 462397 T2569 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /a_c/
   [junit4]   2> 462404 T2569 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 462405 T2569 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 462406 T2570 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 462505 T2569 oasc.ZkTestServer.run start zk server on port:51744
   [junit4]   2> 462506 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 462656 T2576 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@557e44bb name:ZooKeeperConnection Watcher:127.0.0.1:51744 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 462657 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 462657 T2569 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 462663 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 462665 T2578 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2804bc26 name:ZooKeeperConnection Watcher:127.0.0.1:51744/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 462665 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 462665 T2569 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 462668 T2569 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 462670 T2569 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 462671 T2569 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 462673 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 462673 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 462676 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 462677 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 462679 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 462679 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 462681 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 462682 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 462683 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 462684 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 462685 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 462686 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 462687 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 462688 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 462690 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 462690 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 462692 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 462693 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 462697 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 462697 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 462700 T2569 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 462701 T2569 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 462963 T2569 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 462967 T2569 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54996
   [junit4]   2> 462967 T2569 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 462967 T2569 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 462968 T2569 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002
   [junit4]   2> 462968 T2569 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/'
   [junit4]   2> 462989 T2569 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/solr.xml
   [junit4]   2> 463025 T2569 oasc.CoreContainer.<init> New CoreContainer 528920303
   [junit4]   2> 463025 T2569 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/]
   [junit4]   2> 463026 T2569 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 463027 T2569 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 463027 T2569 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 463027 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 463027 T2569 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 463028 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 463028 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 463028 T2569 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 463028 T2569 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 463029 T2569 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 463029 T2569 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 463030 T2569 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 463030 T2569 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51744/solr
   [junit4]   2> 463030 T2569 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 463031 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 463032 T2589 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4346dd14 name:ZooKeeperConnection Watcher:127.0.0.1:51744 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 463032 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 463034 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 463035 T2591 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@66a50c69 name:ZooKeeperConnection Watcher:127.0.0.1:51744/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 463035 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 463036 T2569 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 463038 T2569 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 463040 T2569 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 463042 T2569 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 463043 T2569 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 463055 T2569 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 463057 T2569 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54996_a_c
   [junit4]   2> 463058 T2569 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54996_a_c
   [junit4]   2> 463059 T2569 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 463061 T2569 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 463064 T2569 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:54996_a_c
   [junit4]   2> 463064 T2569 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 463067 T2569 oasc.Overseer.start Overseer (id=91854423518806019-127.0.0.1:54996_a_c-n_0000000000) starting
   [junit4]   2> 463069 T2569 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 463075 T2593 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 463076 T2569 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 463078 T2569 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 463079 T2569 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 463081 T2592 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 463084 T2594 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
   [junit4]   2> 463085 T2594 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 463086 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 463086 T2594 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 463087 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 463087 T2592 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:54996/a_c",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54996_a_c",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 463087 T2592 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
   [junit4]   2> 463088 T2592 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 463090 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 463090 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 464086 T2594 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 464086 T2594 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 464087 T2594 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 464087 T2594 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 464088 T2594 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 464088 T2594 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/collection1/'
   [junit4]   2> 464089 T2594 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/collection1/lib/.svn/' to classloader
   [junit4]   2> 464089 T2594 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/collection1/lib/classes/' to classloader
   [junit4]   2> 464089 T2594 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/collection1/lib/README' to classloader
   [junit4]   2> 464124 T2594 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 464161 T2594 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 464163 T2594 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 464170 T2594 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 464536 T2594 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 464536 T2594 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 464537 T2594 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 464542 T2594 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 464544 T2594 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 464553 T2594 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 464556 T2594 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 464559 T2594 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 464560 T2594 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 464560 T2594 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 464560 T2594 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 464561 T2594 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 464561 T2594 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 464561 T2594 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 464561 T2594 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 464561 T2594 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-002/collection1/, dataDir=./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/control/data/
   [junit4]   2> 464562 T2594 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@86d7ec4
   [junit4]   2> 464562 T2594 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/control/data
   [junit4]   2> 464562 T2594 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/control/data/index/
   [junit4]   2> 464563 T2594 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 464563 T2594 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/control/data/index
   [junit4]   2> 464563 T2594 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1745223799, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 464564 T2594 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@20434916 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@253e7e3a),segFN=segments_1,generation=1}
   [junit4]   2> 464564 T2594 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 464567 T2594 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 464567 T2594 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 464568 T2594 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 464568 T2594 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 464568 T2594 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 464568 T2594 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 464569 T2594 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 464569 T2594 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 464569 T2594 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 464569 T2594 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 464570 T2594 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 464570 T2594 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 464570 T2594 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 464571 T2594 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 464571 T2594 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 464571 T2594 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 464574 T2594 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 464576 T2594 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 464576 T2594 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 464577 T2594 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=31.3447265625, floorSegmentMB=0.548828125, forceMergeDeletesPctAllowed=13.355440654856919, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 464577 T2594 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@20434916 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@253e7e3a),segFN=segments_1,generation=1}
   [junit4]   2> 464577 T2594 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 464578 T2594 oass.SolrIndexSearcher.<init> Opening Searcher@189481d1[collection1] main
   [junit4]   2> 464578 T2594 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 464579 T2594 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 464579 T2594 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 464579 T2594 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 464579 T2594 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 464580 T2594 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 464580 T2594 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 464580 T2594 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 464580 T2594 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 464581 T2594 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 464583 T2595 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@189481d1[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 464584 T2594 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 464584 T2598 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54996/a_c collection:control_collection shard:shard1
   [junit4]   2> 464585 T2569 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 464585 T2569 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 464585 T2598 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 464586 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 464587 T2600 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47bc84c8 name:ZooKeeperConnection Watcher:127.0.0.1:51744/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 464588 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 464589 T2569 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 464591 T2569 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 464592 T2598 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 464889 T2569 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 464890 T2569 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 464892 T2569 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34558
   [junit4]   2> 464893 T2569 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 464893 T2569 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 464894 T2569 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003
   [junit4]   2> 464894 T2569 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/'
   [junit4]   2> 464914 T2569 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/solr.xml
   [junit4]   2> 464967 T2569 oasc.CoreContainer.<init> New CoreContainer 138010443
   [junit4]   2> 464967 T2569 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/]
   [junit4]   2> 464968 T2569 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 464969 T2569 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 464969 T2569 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 464969 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 464969 T2569 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 464970 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 464970 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 464970 T2569 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 464970 T2569 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 464971 T2569 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 464971 T2569 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 464971 T2569 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 464972 T2569 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51744/solr
   [junit4]   2> 464972 T2569 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 464973 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 465098 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465101 T2611 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f72b925 name:ZooKeeperConnection Watcher:127.0.0.1:51744 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 465101 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 465102 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 465101 T2598 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 465102 T2598 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C267 name=collection1 org.apache.solr.core.SolrCore@6c53d600 url=http://127.0.0.1:54996/a_c/collection1 node=127.0.0.1:54996_a_c C267_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:54996/a_c, core=collection1, node_name=127.0.0.1:54996_a_c}
   [junit4]   2> 465102 T2598 C267 P54996 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54996/a_c/collection1/
   [junit4]   2> 465103 T2598 C267 P54996 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 465103 T2598 C267 P54996 oasc.SyncStrategy.syncToMe http://127.0.0.1:54996/a_c/collection1/ has no replicas
   [junit4]   2> 465103 T2598 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54996/a_c/collection1/ shard1
   [junit4]   2> 465103 T2598 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 465104 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465105 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 465105 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 465105 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 465107 T2613 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e268afa name:ZooKeeperConnection Watcher:127.0.0.1:51744/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 465107 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 465111 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465112 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 465114 T2569 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 465115 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465217 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 465217 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 465218 T2613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 465268 T2598 oasc.ZkController.register We are http://127.0.0.1:54996/a_c/collection1/ and leader is http://127.0.0.1:54996/a_c/collection1/
   [junit4]   2> 465269 T2598 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54996/a_c
   [junit4]   2> 465269 T2598 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 465269 T2598 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
   [junit4]   2> 465269 T2598 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 465272 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465272 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465272 T2598 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 465273 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465274 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 465275 T2592 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:54996/a_c",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:54996_a_c",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 465276 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 465934 T2613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 465934 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 465934 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 466117 T2569 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34558_a_c
   [junit4]   2> 466118 T2569 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34558_a_c
   [junit4]   2> 466119 T2591 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 466120 T2613 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 466120 T2600 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 466125 T2614 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 466125 T2614 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 466126 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 466126 T2614 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 466127 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 466127 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 466127 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 466128 T2592 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:34558/a_c",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:34558_a_c",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 466128 T2592 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
   [junit4]   2> 466128 T2592 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 466130 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 466232 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 466232 T2613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 466232 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467127 T2614 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 467127 T2614 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 467128 T2614 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 467128 T2614 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 467129 T2614 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 467129 T2614 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/collection1/'
   [junit4]   2> 467130 T2614 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/collection1/lib/.svn/' to classloader
   [junit4]   2> 467130 T2614 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/collection1/lib/classes/' to classloader
   [junit4]   2> 467130 T2614 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/collection1/lib/README' to classloader
   [junit4]   2> 467167 T2614 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 467203 T2614 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 467205 T2614 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 467212 T2614 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 467560 T2614 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 467561 T2614 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 467561 T2614 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 467566 T2614 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 467569 T2614 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 467579 T2614 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 467582 T2614 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 467585 T2614 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 467585 T2614 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 467586 T2614 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 467586 T2614 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 467587 T2614 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 467587 T2614 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 467587 T2614 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 467587 T2614 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 467587 T2614 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-003/collection1/, dataDir=./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty1/
   [junit4]   2> 467588 T2614 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@86d7ec4
   [junit4]   2> 467588 T2614 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty1
   [junit4]   2> 467589 T2614 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty1/index/
   [junit4]   2> 467589 T2614 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
   [junit4]   2> 467589 T2614 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty1/index
   [junit4]   2> 467590 T2614 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1745223799, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 467590 T2614 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@3db653c3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f03c9a2),segFN=segments_1,generation=1}
   [junit4]   2> 467591 T2614 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 467595 T2614 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 467595 T2614 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 467596 T2614 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 467596 T2614 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 467596 T2614 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 467596 T2614 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 467597 T2614 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 467597 T2614 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 467597 T2614 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 467597 T2614 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 467598 T2614 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 467598 T2614 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 467598 T2614 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 467599 T2614 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 467599 T2614 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 467600 T2614 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 467602 T2614 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 467604 T2614 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 467604 T2614 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 467605 T2614 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=31.3447265625, floorSegmentMB=0.548828125, forceMergeDeletesPctAllowed=13.355440654856919, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 467605 T2614 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@3db653c3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f03c9a2),segFN=segments_1,generation=1}
   [junit4]   2> 467605 T2614 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 467606 T2614 oass.SolrIndexSearcher.<init> Opening Searcher@11472d1d[collection1] main
   [junit4]   2> 467606 T2614 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 467607 T2614 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 467607 T2614 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 467607 T2614 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 467608 T2614 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 467608 T2614 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 467608 T2614 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 467608 T2614 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 467609 T2614 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 467609 T2614 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 467612 T2615 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11472d1d[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 467614 T2614 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 467614 T2618 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:34558/a_c collection:collection1 shard:shard1
   [junit4]   2> 467615 T2569 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 467615 T2569 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 467615 T2618 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 467620 T2618 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 467621 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467621 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467621 T2618 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 467621 T2618 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 467621 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> ASYNC  NEW_CORE C268 name=collection1 org.apache.solr.core.SolrCore@63c3f668 url=http://127.0.0.1:34558/a_c/collection1 node=127.0.0.1:34558_a_c C268_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:34558/a_c, core=collection1, node_name=127.0.0.1:34558_a_c}
   [junit4]   2> 467622 T2618 C268 P34558 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34558/a_c/collection1/
   [junit4]   2> 467622 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 467622 T2618 C268 P34558 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 467622 T2618 C268 P34558 oasc.SyncStrategy.syncToMe http://127.0.0.1:34558/a_c/collection1/ has no replicas
   [junit4]   2> 467622 T2618 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34558/a_c/collection1/ shard1
   [junit4]   2> 467623 T2618 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 467624 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467726 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467726 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467726 T2613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467729 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467729 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467729 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 467731 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467833 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467833 T2613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467833 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467850 T2569 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 467851 T2569 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 467852 T2569 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34077
   [junit4]   2> 467853 T2569 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 467853 T2569 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 467853 T2569 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004
   [junit4]   2> 467854 T2569 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/'
   [junit4]   2> 467867 T2569 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/solr.xml
   [junit4]   2> 467878 T2618 oasc.ZkController.register We are http://127.0.0.1:34558/a_c/collection1/ and leader is http://127.0.0.1:34558/a_c/collection1/
   [junit4]   2> 467879 T2618 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34558/a_c
   [junit4]   2> 467879 T2618 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 467879 T2618 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 467879 T2618 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 467881 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467881 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467881 T2618 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 467881 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467882 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 467883 T2592 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:34558/a_c",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:34558_a_c",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 467889 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 467906 T2569 oasc.CoreContainer.<init> New CoreContainer 77419369
   [junit4]   2> 467907 T2569 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/]
   [junit4]   2> 467908 T2569 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 467908 T2569 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 467908 T2569 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 467908 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 467909 T2569 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 467909 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 467909 T2569 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 467909 T2569 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 467910 T2569 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 467910 T2569 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 467910 T2569 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 467911 T2569 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 467911 T2569 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51744/solr
   [junit4]   2> 467911 T2569 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 467912 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 467914 T2629 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74cf14dc name:ZooKeeperConnection Watcher:127.0.0.1:51744 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 467914 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 467916 T2569 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 467917 T2631 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d4a93e6 name:ZooKeeperConnection Watcher:127.0.0.1:51744/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 467917 T2569 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 467922 T2569 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 467992 T2631 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467992 T2613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467992 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 467992 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 468925 T2569 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34077_a_c
   [junit4]   2> 468926 T2569 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34077_a_c
   [junit4]   2> 468928 T2631 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 468928 T2591 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 468928 T2600 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 468929 T2613 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 468934 T2632 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 468934 T2632 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 468935 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 468935 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 468936 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 468935 T2632 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 468937 T2592 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 468937 T2592 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:34077/a_c",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:34077_a_c",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 468938 T2592 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 468938 T2592 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 468939 T2591 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 469041 T2591 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 469041 T2600 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 469041 T2613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 469041 T2631 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 469936 T2632 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 469936 T2632 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 469937 T2632 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 469937 T2632 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 469938 T2632 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 469938 T2632 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/collection1/'
   [junit4]   2> 469939 T2632 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/collection1/lib/.svn/' to classloader
   [junit4]   2> 469939 T2632 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/collection1/lib/classes/' to classloader
   [junit4]   2> 469940 T2632 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/collection1/lib/README' to classloader
   [junit4]   2> 469975 T2632 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 470012 T2632 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 470013 T2632 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 470020 T2632 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 470368 T2632 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 470368 T2632 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 470369 T2632 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 470374 T2632 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 470376 T2632 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 470385 T2632 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 470388 T2632 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 470391 T2632 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 470392 T2632 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 470392 T2632 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 470392 T2632 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 470393 T2632 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 470393 T2632 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 470393 T2632 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 470393 T2632 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 470394 T2632 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-004/collection1/, dataDir=./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty2/
   [junit4]   2> 470394 T2632 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@86d7ec4
   [junit4]   2> 470395 T2632 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty2
   [junit4]   2> 470395 T2632 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty2/index/
   [junit4]   2> 470395 T2632 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
   [junit4]   2> 470395 T2632 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SSLMigrationTest-BA01CF3B0461BD72-001/tempDir-001/jetty2/index
   [junit4]   2> 470396 T2632 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1745223799, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 470396 T2632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@11e14d7d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@59e84d54),segFN=segments_1,generation=1}
   [junit4]   2> 470396 T2632 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 470400 T2632 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 470401 T2632 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 470401 T2632 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [

[...truncated too long message...]

SLMigrationTest (suite) <<<
   [junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 9 threads leaked from SUITE scope at org.apache.solr.cloud.SSLMigrationTest: 
   [junit4]    >    1) Thread[id=2736, name=qtp1954295020-2736, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=2739, name=qtp1954295020-2739, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    3) Thread[id=2735, name=qtp1954295020-2735, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    4) Thread[id=2740, name=qtp1954295020-2740, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    5) Thread[id=2734, name=HashSessionScavenger-99, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.util.TimerThread.mainLoop(Timer.java:552)
   [junit4]    >         at java.util.TimerThread.run(Timer.java:505)
   [junit4]    >    6) Thread[id=2737, name=qtp1954295020-2737, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    7) Thread[id=2741, name=qtp1954295020-2741, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    8) Thread[id=2738, name=qtp1954295020-2738, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    9) Thread[id=2742, name=qtp1954295020-2742, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]    >         at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:342)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:526)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool.access$600(QueuedThreadPool.java:44)
   [junit4]    >         at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([BA01CF3B0461BD72]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=2734, name=HashSessionScavenger-99, state=TIMED_WAITING, group=TGRP-SSLMigrationTest]
   [junit4]    >         at java.lang.Object.wait(Native Method)
   [junit4]    >         at java.util.TimerThread.mainLoop(Timer.java:552)
   [junit4]    >         at java.util.TimerThread.run(Timer.java:505)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([BA01CF3B0461BD72]:0)
   [junit4] Completed on J0 in 46.92s, 1 test, 3 errors <<< FAILURES!

[...truncated 847 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1297: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:921: There were test failures: 396 suites, 1640 tests, 2 suite-level errors, 1 error, 855 ignored (10 assumptions)

Total time: 49 minutes 16 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_55 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message