lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-4.x-Java7 - Build # 1440 - Failure
Date Thu, 25 Jul 2013 01:04:15 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-Java7/1440/

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.AliasIntegrationTest

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.AliasIntegrationTest:     1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]         at java.net.PlainSocketImpl.socketConnect(Native Method)         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391)         at java.net.Socket.connect(Socket.java:579)         at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)         at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)         at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)         at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)         at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)         at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:722)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.AliasIntegrationTest: 
   1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
        at java.net.PlainSocketImpl.socketConnect(Native Method)
        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391)
        at java.net.Socket.connect(Socket.java:579)
        at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
        at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
        at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
        at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
        at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
        at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:722)
	at __randomizedtesting.SeedInfo.seed([3F7679C7E4926DB9]:0)


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

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]         at java.net.PlainSocketImpl.socketConnect(Native Method)         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391)         at java.net.Socket.connect(Socket.java:579)         at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)         at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)         at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)         at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)         at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)         at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:722)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
        at java.net.PlainSocketImpl.socketConnect(Native Method)
        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391)
        at java.net.Socket.connect(Socket.java:579)
        at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
        at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
        at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
        at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
        at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
        at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:722)
	at __randomizedtesting.SeedInfo.seed([3F7679C7E4926DB9]:0)




Build Log:
[...truncated 9326 lines...]
   [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
   [junit4]   2> 22977 T38 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /v/hn
   [junit4]   2> 22985 T38 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1374712852315
   [junit4]   2> 22994 T38 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 23002 T39 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 23304 T38 oasc.ZkTestServer.run start zk server on port:65381
   [junit4]   2> 23606 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 23649 T40 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
   [junit4]   2> 25717 T43 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4bc781ba name:ZooKeeperConnection Watcher:127.0.0.1:65381 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 25718 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 25724 T38 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 25840 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 25844 T47 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14d7c58a name:ZooKeeperConnection Watcher:127.0.0.1:65381/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 25844 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 25861 T38 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 25868 T38 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 25873 T38 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 25879 T38 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 25885 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 25922 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 25939 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 25939 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 26047 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 26048 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 26054 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 26055 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 26061 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 26063 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 26069 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 26070 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 26076 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 26077 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 26083 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 26084 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 26090 T38 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 26091 T38 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 26439 T38 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 26449 T38 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:13412
   [junit4]   2> 26450 T38 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 26451 T38 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 26452 T38 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428
   [junit4]   2> 26452 T38 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/'
   [junit4]   2> 26499 T38 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/solr.xml
   [junit4]   2> 26619 T38 oasc.CoreContainer.<init> New CoreContainer 1856259094
   [junit4]   2> 26620 T38 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/]
   [junit4]   2> 26622 T38 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 26623 T38 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 26624 T38 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 26625 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 26625 T38 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 26626 T38 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 26627 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 26628 T38 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 26628 T38 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 26629 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 26650 T38 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65381/solr
   [junit4]   2> 26690 T38 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 26692 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 26713 T58 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@86f0f53 name:ZooKeeperConnection Watcher:127.0.0.1:65381 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 26714 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 26726 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 26750 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 26753 T60 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@24022385 name:ZooKeeperConnection Watcher:127.0.0.1:65381/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 26754 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 26774 T38 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 26796 T38 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 26822 T38 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 26826 T38 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:13412_v%2Fhn
   [junit4]   2> 26836 T38 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:13412_v%2Fhn
   [junit4]   2> 26844 T38 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 26884 T38 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 26890 T38 oasc.Overseer.start Overseer (id=90093181525164035-127.0.0.1:13412_v%2Fhn-n_0000000000) starting
   [junit4]   2> 26931 T38 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 26962 T62 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 26962 T38 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 26967 T38 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 26971 T38 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 27010 T61 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 27039 T63 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 27040 T63 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 27043 T63 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 28524 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 28533 T61 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:13412_v%2Fhn",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:13412/v/hn"}
   [junit4]   2> 28534 T61 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
   [junit4]   2> 28576 T61 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 28621 T60 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> 29046 T63 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 29047 T63 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/collection1
   [junit4]   2> 29047 T63 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 29049 T63 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 29049 T63 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 29052 T63 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/collection1/'
   [junit4]   2> 29054 T63 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/collection1/lib/classes/' to classloader
   [junit4]   2> 29055 T63 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/collection1/lib/README' to classloader
   [junit4]   2> 29183 T63 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
   [junit4]   2> 29261 T63 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 29363 T63 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 29443 T63 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 30223 T63 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 30223 T63 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 30225 T63 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 30241 T63 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 30245 T63 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 30276 T63 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 30283 T63 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 30288 T63 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 30290 T63 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 30290 T63 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 30291 T63 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 30292 T63 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 30292 T63 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 30293 T63 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 30293 T63 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1374712855428/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/control/data/
   [junit4]   2> 30294 T63 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@23d48279
   [junit4]   2> 30295 T63 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/control/data
   [junit4]   2> 30295 T63 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/control/data/index/
   [junit4]   2> 30296 T63 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1374712852315/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 30296 T63 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/control/data/index
   [junit4]   2> 30301 T63 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4c191dcf lockFactory=org.apache.lucene.store.NativeFSLockFactory@9ca617e)),segFN=segments_1,generation=1}
   [junit4]   2> 30302 T63 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 30387 T63 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 30388 T63 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 30389 T63 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 30390 T63 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 30391 T63 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 30391 T63 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 30392 T63 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 30392 T63 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 30393 T63 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 30413 T63 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 30421 T63 oass.SolrIndexSearcher.<init> Opening Searcher@1cd08da6 main
   [junit4]   2> 30423 T63 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 30423 T63 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 30430 T64 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cd08da6 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 30434 T63 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 30434 T63 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:13412/v/hn collection:control_collection shard:shard1
   [junit4]   2> 30449 T63 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
   [junit4]   2> 30465 T63 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 30493 T63 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 30505 T63 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 30506 T63 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 30513 T63 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:13412/v/hn/collection1/
   [junit4]   2> 30514 T63 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 30515 T63 oasc.SyncStrategy.syncToMe http://127.0.0.1:13412/v/hn/collection1/ has no replicas
   [junit4]   2> 30515 T63 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:13412/v/hn/collection1/ shard1
   [junit4]   2> 30516 T63 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 31633 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 31655 T60 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> 31706 T63 oasc.ZkController.register We are http://127.0.0.1:13412/v/hn/collection1/ and leader is http://127.0.0.1:13412/v/hn/collection1/
   [junit4]   2> 31707 T63 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:13412/v/hn
   [junit4]   2> 31707 T63 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 31708 T63 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 31708 T63 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 31711 T63 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 31714 T38 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0
   [junit4]   2> 31715 T38 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 31850 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 31881 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:
   [junit4]   2> 31890 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 31893 T67 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4bb95ca4 name:ZooKeeperConnection Watcher:127.0.0.1:65381/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 31894 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 31896 T38 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 31904 T38 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
   [junit4]   2> 32249 T38 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 32253 T38 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:42488
   [junit4]   2> 32254 T38 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 32255 T38 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 32256 T38 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233
   [junit4]   2> 32257 T38 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/'
   [junit4]   2> 32298 T38 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/solr.xml
   [junit4]   2> 32407 T38 oasc.CoreContainer.<init> New CoreContainer 546299815
   [junit4]   2> 32408 T38 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/]
   [junit4]   2> 32411 T38 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 32411 T38 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 32412 T38 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 32413 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 32414 T38 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 32414 T38 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 32415 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 32416 T38 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 32417 T38 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 32417 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 32438 T38 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65381/solr
   [junit4]   2> 32439 T38 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 32440 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 32444 T78 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d3469f6 name:ZooKeeperConnection Watcher:127.0.0.1:65381 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 32444 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 32448 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 32466 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 32469 T80 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@59fb6ce4 name:ZooKeeperConnection Watcher:127.0.0.1:65381/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 32470 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 32479 T38 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 33168 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 33171 T61 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:13412_v%2Fhn",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:13412/v/hn"}
   [junit4]   2> 33310 T80 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> 33310 T60 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> 33310 T67 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> 33486 T38 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42488_v%2Fhn
   [junit4]   2> 33489 T38 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42488_v%2Fhn
   [junit4]   2> 33494 T67 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 33494 T60 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 33494 T80 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 33495 T60 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 33494 T67 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 33495 T80 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 33512 T81 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 33513 T81 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 33515 T81 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 34819 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 34822 T61 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:42488_v%2Fhn",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:42488/v/hn"}
   [junit4]   2> 34822 T61 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
   [junit4]   2> 34823 T61 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 34837 T60 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> 34837 T80 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> 34837 T67 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> 35518 T81 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 35518 T81 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/collection1
   [junit4]   2> 35519 T81 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 35521 T81 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 35521 T81 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 35523 T81 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/collection1/'
   [junit4]   2> 35525 T81 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/collection1/lib/README' to classloader
   [junit4]   2> 35526 T81 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/collection1/lib/classes/' to classloader
   [junit4]   2> 35603 T81 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
   [junit4]   2> 35700 T81 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 35802 T81 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 35870 T81 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 36640 T81 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 36640 T81 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 36642 T81 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 36658 T81 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 36661 T81 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 36694 T81 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 36700 T81 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 36706 T81 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 36708 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 36708 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 36708 T81 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 36710 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 36711 T81 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 36711 T81 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 36711 T81 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1374712861233/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty1/
   [junit4]   2> 36712 T81 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@23d48279
   [junit4]   2> 36714 T81 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty1
   [junit4]   2> 36714 T81 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty1/index/
   [junit4]   2> 36714 T81 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty1/index' doesn't exist. Creating new index...
   [junit4]   2> 36715 T81 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty1/index
   [junit4]   2> 36718 T81 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7e973909 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d97768d)),segFN=segments_1,generation=1}
   [junit4]   2> 36719 T81 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 36726 T81 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 36727 T81 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 36728 T81 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 36729 T81 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 36730 T81 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 36731 T81 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 36731 T81 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 36732 T81 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 36733 T81 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 36747 T81 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 36753 T81 oass.SolrIndexSearcher.<init> Opening Searcher@735b8caa main
   [junit4]   2> 36755 T81 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 36755 T81 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 36764 T82 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@735b8caa main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 36768 T81 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 36768 T81 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42488/v/hn collection:collection1 shard:shard1
   [junit4]   2> 36769 T81 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
   [junit4]   2> 36786 T81 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 36807 T81 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 36819 T81 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 36820 T81 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 36820 T81 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42488/v/hn/collection1/
   [junit4]   2> 36820 T81 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 36821 T81 oasc.SyncStrategy.syncToMe http://127.0.0.1:42488/v/hn/collection1/ has no replicas
   [junit4]   2> 36821 T81 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42488/v/hn/collection1/ shard1
   [junit4]   2> 36822 T81 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 37849 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 37887 T60 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> 37887 T80 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> 37887 T67 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> 37915 T81 oasc.ZkController.register We are http://127.0.0.1:42488/v/hn/collection1/ and leader is http://127.0.0.1:42488/v/hn/collection1/
   [junit4]   2> 37915 T81 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42488/v/hn
   [junit4]   2> 37916 T81 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 37916 T81 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 37917 T81 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 37920 T81 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 37922 T38 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0
   [junit4]   2> 37924 T38 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 37925 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 38278 T38 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 38282 T38 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:35003
   [junit4]   2> 38283 T38 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 38284 T38 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 38285 T38 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270
   [junit4]   2> 38286 T38 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/'
   [junit4]   2> 38341 T38 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/solr.xml
   [junit4]   2> 38460 T38 oasc.CoreContainer.<init> New CoreContainer 896136118
   [junit4]   2> 38461 T38 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/]
   [junit4]   2> 38463 T38 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 38464 T38 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 38465 T38 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 38465 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 38466 T38 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 38467 T38 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 38467 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 38468 T38 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 38469 T38 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 38470 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 38494 T38 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65381/solr
   [junit4]   2> 38495 T38 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 38496 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 38502 T94 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1603f9aa name:ZooKeeperConnection Watcher:127.0.0.1:65381 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 38503 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 38507 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 38522 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 38525 T96 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7ac7c9cc name:ZooKeeperConnection Watcher:127.0.0.1:65381/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 38525 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 38539 T38 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 39400 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 39403 T61 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "core_node_name":"core_node1",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:42488_v%2Fhn",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:42488/v/hn"}
   [junit4]   2> 39438 T96 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> 39438 T80 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> 39438 T67 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> 39438 T60 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> 39546 T38 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35003_v%2Fhn
   [junit4]   2> 39552 T38 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35003_v%2Fhn
   [junit4]   2> 39557 T60 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 39557 T96 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 39557 T67 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 39557 T60 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 39557 T80 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 39558 T67 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 39558 T96 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 39559 T80 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 39570 T97 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 39571 T97 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 39573 T97 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 40947 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 40950 T61 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "core_node_name":"core_node2",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:35003_v%2Fhn",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:35003/v/hn"}
   [junit4]   2> 40950 T61 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
   [junit4]   2> 40951 T61 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 40960 T60 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> 40960 T80 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> 40960 T96 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> 40960 T67 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> 41576 T97 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 41576 T97 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/collection1
   [junit4]   2> 41577 T97 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 41578 T97 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 41579 T97 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 41581 T97 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/collection1/'
   [junit4]   2> 41583 T97 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/collection1/lib/classes/' to classloader
   [junit4]   2> 41585 T97 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/collection1/lib/README' to classloader
   [junit4]   2> 41661 T97 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
   [junit4]   2> 41746 T97 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 41849 T97 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 41891 T97 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 42748 T97 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 42748 T97 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 42752 T97 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 42776 T97 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 42781 T97 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 42821 T97 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 42829 T97 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 42835 T97 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 42837 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 42837 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 42838 T97 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 42840 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 42840 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 42841 T97 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 42841 T97 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1374712867270/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty2/
   [junit4]   2> 42842 T97 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@23d48279
   [junit4]   2> 42844 T97 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty2
   [junit4]   2> 42844 T97 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty2/index/
   [junit4]   2> 42844 T97 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty2/index' doesn't exist. Creating new index...
   [junit4]   2> 42845 T97 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty2/index
   [junit4]   2> 42850 T97 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@37f62c6f lockFactory=org.apache.lucene.store.NativeFSLockFactory@63282cef)),segFN=segments_1,generation=1}
   [junit4]   2> 42851 T97 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 42861 T97 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 42862 T97 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 42863 T97 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 42864 T97 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 42866 T97 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 42866 T97 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 42867 T97 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 42868 T97 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 42869 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 42891 T97 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 42902 T97 oass.SolrIndexSearcher.<init> Opening Searcher@76536b78 main
   [junit4]   2> 42903 T97 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 42904 T97 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 42915 T98 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@76536b78 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 42921 T97 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 42921 T97 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:35003/v/hn collection:collection1 shard:shard1
   [junit4]   2> 42922 T97 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
   [junit4]   2> 42952 T97 oasc.ZkController.register We are http://127.0.0.1:35003/v/hn/collection1/ and leader is http://127.0.0.1:42488/v/hn/collection1/
   [junit4]   2> 42952 T97 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35003/v/hn
   [junit4]   2> 42953 T97 oasc.ZkController.checkRecovery Core needs to recover:collection1
   [junit4]   2> 42953 T97 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
   [junit4]   2> ASYNC  NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@63c6ec18 url=http://127.0.0.1:35003/v/hn/collection1 node=127.0.0.1:35003_v%2Fhn C4_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, node_name=127.0.0.1:35003_v%2Fhn, base_url=http://127.0.0.1:35003/v/hn}
   [junit4]   2> 42959 T99 C4 P35003 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
   [junit4]   2> 42959 T97 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 42960 T99 C4 P35003 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
   [junit4]   2> 42961 T99 C4 P35003 oasc.ZkController.publish publishing core=collection1 state=recovering
   [junit4]   2> 42961 T99 C4 P35003 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 42962 T38 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0
   [junit4]   2> 42962 T38 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 42963 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 42996 T99 C4 P35003 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 43347 T38 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 43351 T38 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:29786
   [junit4]   2> 43352 T38 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 43353 T38 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 43353 T38 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307
   [junit4]   2> 43354 T38 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/'
   [junit4]   2> 43409 T38 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/solr.xml
   [junit4]   2> 43549 T38 oasc.CoreContainer.<init> New CoreContainer 2011441115
   [junit4]   2> 43550 T38 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/]
   [junit4]   2> 43552 T38 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 43553 T38 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 43554 T38 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 43554 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 43555 T38 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 43556 T38 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 43556 T38 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 43557 T38 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 43558 T38 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 43558 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 43578 T38 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:65381/solr
   [junit4]   2> 43579 T38 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 43580 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 43586 T111 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7aea369a name:ZooKeeperConnection Watcher:127.0.0.1:65381 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 43586 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 43589 T38 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 43599 T38 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 43601 T113 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@65a8f181 name:ZooKeeperConnection Watcher:127.0.0.1:65381/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 43602 T38 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 43614 T38 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 43970 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 43973 T61 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "core_node_name":"core_node2",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "state":"recovering",
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:35003_v%2Fhn",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:35003/v/hn"}
   [junit4]   2> 43995 T113 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> 43995 T80 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> 43995 T96 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> 43995 T60 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> 43995 T67 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> 44090 T70 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true
   [junit4]   2> 44091 T70 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true for: 0 seconds.
   [junit4]   2> 44092 T70 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={coreNodeName=core_node2&state=recovering&nodeName=127.0.0.1:35003_v%252Fhn&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=6 
   [junit4]   2> 44620 T38 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:29786_v%2Fhn
   [junit4]   2> 44651 T38 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:29786_v%2Fhn
   [junit4]   2> 44655 T113 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 44656 T96 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 44656 T67 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 44657 T96 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 44656 T80 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 44656 T113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 44656 T60 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 44658 T80 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 44657 T67 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 44660 T60 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 44674 T114 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 44675 T114 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 44677 T114 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 45502 T61 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 45505 T61 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "core_node_name":"core_node3",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "shard_state":"active",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "shard_range":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:29786_v%2Fhn",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:29786/v/hn"}
   [junit4]   2> 45507 T61 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
   [junit4]   2> 45507 T61 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 45521 T113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 45522 T96 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 45522 T80 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 45521 T60 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 45521 T67 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 45678 T114 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 45679 T114 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/collection1
   [junit4]   2> 45680 T114 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 45681 T114 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 45682 T114 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 45684 T114 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/collection1/'
   [junit4]   2> 45687 T114 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/collection1/lib/README' to classloader
   [junit4]   2> 45689 T114 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/collection1/lib/classes/' to classloader
   [junit4]   2> 45819 T114 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
   [junit4]   2> 45901 T114 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 46004 T114 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 46044 T114 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> ASYNC  NEW_CORE C5 name=collection1 org.apache.solr.core.SolrCore@63c6ec18 url=http://127.0.0.1:35003/v/hn/collection1 node=127.0.0.1:35003_v%2Fhn C5_STATE=coll:collection1 core:collection1 props:{state=recovering, core=collection1, node_name=127.0.0.1:35003_v%2Fhn, base_url=http://127.0.0.1:35003/v/hn}
   [junit4]   2> 46160 T99 C5 P35003 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:42488/v/hn/collection1/ core=collection1 - recoveringAfterStartup=true
   [junit4]   2> 46178 T99 C5 P35003 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
   [junit4]   2> 46217 T99 C5 P35003 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:35003/v/hn START replicas=[http://127.0.0.1:42488/v/hn/collection1/] nUpdates=100
   [junit4]   2> 46235 T99 C5 P35003 oasu.PeerSync.sync WARN no frame of reference to tell of we've missed updates
   [junit4]   2> 46236 T99 C5 P35003 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
   [junit4]   2> 46236 T99 C5 P35003 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
   [junit4]   2> 46237 T99 C5 P35003 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
   [junit4]   2> 46237 T99 C5 P35003 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 46238 T99 C5 P35003 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:42488/v/hn/collection1/. core=collection1
   [junit4]   2> 46238 T99 C5 P35003 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> ASYNC  NEW_CORE C6 name=collection1 org.apache.solr.core.SolrCore@77dadfb3 url=http://127.0.0.1:42488/v/hn/collection1 node=127.0.0.1:42488_v%2Fhn C6_STATE=coll:collection1 core:collection1 props:{state=active, core=collection1, node_name=127.0.0.1:42488_v%2Fhn, base_url=http://127.0.0.1:42488/v/hn, leader=true}
   [junit4]   2> 46314 T72 C6 P42488 oasc.SolrCore.execute [collection1] webapp=/v/hn path=/get params={getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=12 
   [junit4]   2> 46363 T71 C6 P42488 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 46367 T71 C6 P42488 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7e973909 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d97768d)),segFN=segments_1,generation=1}
   [junit4]   2> 46367 T71 C6 P42488 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 46368 T71 C6 P42488 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 46369 T71 C6 P42488 oass.SolrIndexSearcher.<init> Opening Searcher@14e5e48c realtime
   [junit4]   2> 46370 T71 C6 P42488 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 46370 T71 C6 P42488 oasup.LogUpdateProcessor.finish [collection1] webapp=/v/hn path=/update params={waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 67
   [junit4]   2> 46372 T99 C5 P35003 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 46373 T99 C5 P35003 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
   [junit4]   2> 46407 T99 C5 P35003 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
   [junit4]   2> 46502 T73 C6 P42488 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 46503 T73 C6 P42488 oasc.SolrCore.execute [collection1] webapp=/v/hn path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=92 
   [junit4]   2> 46505 T99 C5 P35003 oasc.RecoveryStrategy.replay No replay needed. core=collection1
   [junit4]   2> 46506 T99 C5 P35003 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
   [junit4]   2> 46506 T99 C5 P35003 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 46507 T99 C5 P35003 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 46509 T99 C5 P35003 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
   [junit4]   2> 46851 T114 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 46852 T114 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 46853 T114 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 46870 T114 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 46874 T114 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 46905 T114 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 46924 T114 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 46929 T114 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 46931 T114 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 46931 T114 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 46932 T114 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 46933 T114 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 46933 T114 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 46934 T114 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 46934 T114 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1374712872307/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty3/
   [junit4]   2> 46935 T114 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@23d48279
   [junit4]   2> 46936 T114 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty3
   [junit4]   2> 46936 T114 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty3/index/
   [junit4]   2> 46936 T114 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty3/index' doesn't exist. Creating new index...
   [junit4]   2> 46937 T114 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1374712852315/jetty3/index
   [junit4]   2> 46941 T114 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7bf89b42 lockFactory=org.apache.lucene.store.NativeFSLockFactory@37e64c3a)),segFN=segments_1,generation=1}
   [junit4]   2> 46942 T114 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 46950 T114 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 46950 T114 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 46951 T114 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 46952 T114 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 46953 T114 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 46953 T114 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 46959 T114 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 46960 T114 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 46961 T114 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 46974 T114 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 46981 T114 oass.SolrIndexSearcher.<init> Opening Searcher@392bf3aa main
   [junit4]   2> 46983 T114 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 

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

r.request(HttpSolrServer.java:180)
   [junit4]   2> 	        at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
   [junit4]   2> 122954 T37 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
   [junit4]   2> 	   1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
   [junit4]   2> 125961 T37 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
   [junit4]   2> 	   1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
   [junit4]   2> 	        at java.net.PlainSocketImpl.socketConnect(Native Method)
   [junit4]   2> 	        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
   [junit4]   2> 	        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
   [junit4]   2> 	        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
   [junit4]   2> 	        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391)
   [junit4]   2> 	        at java.net.Socket.connect(Socket.java:579)
   [junit4]   2> 	        at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
   [junit4]   2> 	        at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
   [junit4]   2> 	        at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
   [junit4]   2> 	        at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
   [junit4]   2> 	        at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
   [junit4]   2> 	        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
   [junit4]   2> 	        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
   [junit4]   2> 	        at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
   [junit4]   2> 	        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
   [junit4]   2> 	        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
   [junit4]   2> 	        at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
   [junit4]   2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=ar_SA, timezone=GB-Eire
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_21 (64-bit)/cpus=16,threads=2,free=48373376,total=175112192
   [junit4]   2> NOTE: All tests run in this JVM: [SolrPluginUtilsTest, NumericFieldsTest, TestDynamicFieldResource, AliasIntegrationTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AliasIntegrationTest -Dtests.seed=3F7679C7E4926DB9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_SA -Dtests.timezone=GB-Eire -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | AliasIntegrationTest (suite) <<<
   [junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.AliasIntegrationTest: 
   [junit4]    >    1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
   [junit4]    >         at java.net.PlainSocketImpl.socketConnect(Native Method)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
   [junit4]    >         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391)
   [junit4]    >         at java.net.Socket.connect(Socket.java:579)
   [junit4]    >         at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
   [junit4]    >         at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
   [junit4]    >         at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
   [junit4]    >         at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
   [junit4]    >         at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
   [junit4]    >         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
   [junit4]    >         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
   [junit4]    >         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
   [junit4]    >         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
   [junit4]    >         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
   [junit4]    >         at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:722)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3F7679C7E4926DB9]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=183, name=recoveryCmdExecutor-49-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
   [junit4]    >         at java.net.PlainSocketImpl.socketConnect(Native Method)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
   [junit4]    >         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:391)
   [junit4]    >         at java.net.Socket.connect(Socket.java:579)
   [junit4]    >         at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
   [junit4]    >         at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
   [junit4]    >         at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
   [junit4]    >         at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
   [junit4]    >         at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
   [junit4]    >         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
   [junit4]    >         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
   [junit4]    >         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
   [junit4]    >         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
   [junit4]    >         at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
   [junit4]    >         at org.apache.solr.cloud.SyncStrategy$1.run(SyncStrategy.java:291)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:722)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3F7679C7E4926DB9]:0)
   [junit4] Completed on J0 in 126.13s, 1 test, 2 errors <<< FAILURES!

[...truncated 955 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:395: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:375: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build.xml:181: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/common-build.xml:449: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/lucene/common-build.xml:1233: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/lucene/common-build.xml:876: There were test failures: 319 suites, 1345 tests, 2 suite-level errors, 982 ignored

Total time: 46 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message