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.4-Java6 - Build # 10 - Still Failing
Date Fri, 12 Jul 2013 19:39:02 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.4-Java6/10/

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=1704, name=recoveryCmdExecutor-942-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]         at java.net.PlainSocketImpl.socketConnect(Native Method)         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:327)         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:193)         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:180)         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:384)         at java.net.Socket.connect(Socket.java:546)         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:1146)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:679)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.AliasIntegrationTest: 
   1) Thread[id=1704, name=recoveryCmdExecutor-942-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
        at java.net.PlainSocketImpl.socketConnect(Native Method)
        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:327)
        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:193)
        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:180)
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:384)
        at java.net.Socket.connect(Socket.java:546)
        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:1146)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:679)
	at __randomizedtesting.SeedInfo.seed([60103E8421F9FCF5]: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=1704, name=recoveryCmdExecutor-942-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]         at java.net.PlainSocketImpl.socketConnect(Native Method)         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:327)         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:193)         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:180)         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:384)         at java.net.Socket.connect(Socket.java:546)         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:1146)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)         at java.lang.Thread.run(Thread.java:679)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   1) Thread[id=1704, name=recoveryCmdExecutor-942-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
        at java.net.PlainSocketImpl.socketConnect(Native Method)
        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:327)
        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:193)
        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:180)
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:384)
        at java.net.Socket.connect(Socket.java:546)
        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:1146)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:679)
	at __randomizedtesting.SeedInfo.seed([60103E8421F9FCF5]:0)




Build Log:
[...truncated 8987 lines...]
   [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
   [junit4]   2> 507959 T1530 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /m_jcf/v
   [junit4]   2> 507964 T1530 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./solrtest-AliasIntegrationTest-1373657104912
   [junit4]   2> 507966 T1530 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 507966 T1531 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 508067 T1530 oasc.ZkTestServer.run start zk server on port:23162
   [junit4]   2> 508068 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 508072 T1537 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2204f283 name:ZooKeeperConnection Watcher:127.0.0.1:23162 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 508073 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 508073 T1530 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 508079 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 508088 T1539 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2220a5ce name:ZooKeeperConnection Watcher:127.0.0.1:23162/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 508088 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 508089 T1530 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 508093 T1530 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 508103 T1530 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 508107 T1530 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 508110 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 508111 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 508125 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 508126 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 508229 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 508230 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 508234 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 508234 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 508244 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 508245 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 508249 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 508249 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 508253 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 508253 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 508256 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 508257 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 508260 T1530 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 508261 T1530 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 508536 T1530 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 508542 T1530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:57936
   [junit4]   2> 508543 T1530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 508543 T1530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 508544 T1530 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219
   [junit4]   2> 508544 T1530 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/'
   [junit4]   2> 508581 T1530 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/solr.xml
   [junit4]   2> 508652 T1530 oasc.CoreContainer.<init> New CoreContainer 36436836
   [junit4]   2> 508652 T1530 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/]
   [junit4]   2> 508654 T1530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 508654 T1530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 508655 T1530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 508655 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 508656 T1530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 508656 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 508657 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 508657 T1530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 508658 T1530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 508658 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 508675 T1530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 508676 T1530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:23162/solr
   [junit4]   2> 508676 T1530 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 508677 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 508680 T1550 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15856359 name:ZooKeeperConnection Watcher:127.0.0.1:23162 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 508680 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 508693 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 508704 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 508706 T1552 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1516b175 name:ZooKeeperConnection Watcher:127.0.0.1:23162/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 508706 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 508713 T1530 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 508718 T1530 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 508728 T1530 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 508730 T1530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57936_m_jcf%2Fv
   [junit4]   2> 508737 T1530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57936_m_jcf%2Fv
   [junit4]   2> 508741 T1530 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 508755 T1530 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 508758 T1530 oasc.Overseer.start Overseer (id=90023992028037123-127.0.0.1:57936_m_jcf%2Fv-n_0000000000) starting
   [junit4]   2> 508769 T1530 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 508779 T1554 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 508780 T1530 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 508782 T1530 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 508789 T1530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 508792 T1553 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 508796 T1555 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 508796 T1555 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 508798 T1555 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 510297 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 510298 T1553 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:57936_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:57936/m_jcf/v"}
   [junit4]   2> 510298 T1553 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
   [junit4]   2> 510298 T1553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 510312 T1552 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> 510800 T1555 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 510800 T1555 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/collection1
   [junit4]   2> 510800 T1555 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 510801 T1555 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 510801 T1555 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 510803 T1555 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/collection1/'
   [junit4]   2> 510804 T1555 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/collection1/lib/classes/' to classloader
   [junit4]   2> 510805 T1555 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/collection1/lib/README' to classloader
   [junit4]   2> 510858 T1555 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
   [junit4]   2> 510920 T1555 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 511021 T1555 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 511033 T1555 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 511615 T1555 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 511616 T1555 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 511616 T1555 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 511625 T1555 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 511628 T1555 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 511644 T1555 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 511649 T1555 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 511654 T1555 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 511655 T1555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 511656 T1555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 511656 T1555 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 511657 T1555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 511657 T1555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 511657 T1555 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 511658 T1555 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-controljetty-1373657105219/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/control/data/
   [junit4]   2> 511658 T1555 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@27d08e21
   [junit4]   2> 511659 T1555 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/control/data
   [junit4]   2> 511659 T1555 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/control/data/index/
   [junit4]   2> 511660 T1555 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1373657104911/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 511660 T1555 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/control/data/index
   [junit4]   2> 511665 T1555 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3a4afbbf lockFactory=org.apache.lucene.store.NativeFSLockFactory@c489ba2),segFN=segments_1,generation=1}
   [junit4]   2> 511665 T1555 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 511669 T1555 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 511670 T1555 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 511670 T1555 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 511671 T1555 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 511671 T1555 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 511672 T1555 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 511672 T1555 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 511673 T1555 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 511673 T1555 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 511683 T1555 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 511690 T1555 oass.SolrIndexSearcher.<init> Opening Searcher@395d17f0 main
   [junit4]   2> 511692 T1555 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 511692 T1555 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 511696 T1556 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@395d17f0 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 511697 T1555 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 511697 T1555 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:57936/m_jcf/v collection:control_collection shard:shard1
   [junit4]   2> 511698 T1555 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
   [junit4]   2> 511713 T1555 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 511738 T1555 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 511741 T1555 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 511741 T1555 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 511741 T1555 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:57936/m_jcf/v/collection1/
   [junit4]   2> 511742 T1555 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 511742 T1555 oasc.SyncStrategy.syncToMe http://127.0.0.1:57936/m_jcf/v/collection1/ has no replicas
   [junit4]   2> 511742 T1555 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:57936/m_jcf/v/collection1/ shard1
   [junit4]   2> 511743 T1555 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 511816 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 511832 T1552 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> 511851 T1555 oasc.ZkController.register We are http://127.0.0.1:57936/m_jcf/v/collection1/ and leader is http://127.0.0.1:57936/m_jcf/v/collection1/
   [junit4]   2> 511851 T1555 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:57936/m_jcf/v
   [junit4]   2> 511851 T1555 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 511851 T1555 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 511852 T1555 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 511853 T1555 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 511854 T1530 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1
   [junit4]   2> 511855 T1530 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 511855 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 511861 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:
   [junit4]   2> 511863 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 511865 T1559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a89dbcf name:ZooKeeperConnection Watcher:127.0.0.1:23162/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 511865 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 511867 T1530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 511869 T1530 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
   [junit4]   2> 512138 T1530 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 512141 T1530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:39965
   [junit4]   2> 512141 T1530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 512142 T1530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 512142 T1530 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817
   [junit4]   2> 512143 T1530 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/'
   [junit4]   2> 512180 T1530 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/solr.xml
   [junit4]   2> 512251 T1530 oasc.CoreContainer.<init> New CoreContainer 455300117
   [junit4]   2> 512252 T1530 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/]
   [junit4]   2> 512253 T1530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 512254 T1530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 512254 T1530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 512255 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 512255 T1530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 512256 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 512256 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 512256 T1530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 512257 T1530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 512257 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 512273 T1530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 512273 T1530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:23162/solr
   [junit4]   2> 512274 T1530 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 512275 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 512278 T1570 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2c653c9e name:ZooKeeperConnection Watcher:127.0.0.1:23162 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 512278 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 512293 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 512300 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 512302 T1572 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7cb46557 name:ZooKeeperConnection Watcher:127.0.0.1:23162/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 512302 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 512312 T1530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 513316 T1530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39965_m_jcf%2Fv
   [junit4]   2> 513326 T1530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39965_m_jcf%2Fv
   [junit4]   2> 513330 T1552 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 513330 T1559 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 513331 T1572 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 513332 T1552 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 513346 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 513347 T1553 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:57936_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:57936/m_jcf/v"}
   [junit4]   2> 513349 T1573 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 513350 T1573 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 513360 T1573 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 513360 T1553 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:39965_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:39965/m_jcf/v"}
   [junit4]   2> 513360 T1553 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
   [junit4]   2> 513361 T1553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 513366 T1559 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> 513366 T1552 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> 513366 T1572 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> 514361 T1573 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 514361 T1573 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/collection1
   [junit4]   2> 514361 T1573 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 514362 T1573 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 514362 T1573 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 514364 T1573 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/collection1/'
   [junit4]   2> 514365 T1573 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/collection1/lib/README' to classloader
   [junit4]   2> 514366 T1573 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/collection1/lib/classes/' to classloader
   [junit4]   2> 514425 T1573 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
   [junit4]   2> 514485 T1573 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 514586 T1573 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 514598 T1573 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 515217 T1573 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 515218 T1573 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 515218 T1573 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 515231 T1573 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 515235 T1573 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 515252 T1573 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 515257 T1573 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 515261 T1573 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 515262 T1573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 515263 T1573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 515263 T1573 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 515264 T1573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 515264 T1573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 515265 T1573 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 515265 T1573 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty1-1373657108817/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty1/
   [junit4]   2> 515265 T1573 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@27d08e21
   [junit4]   2> 515266 T1573 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty1
   [junit4]   2> 515267 T1573 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty1/index/
   [junit4]   2> 515267 T1573 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty1/index' doesn't exist. Creating new index...
   [junit4]   2> 515267 T1573 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty1/index
   [junit4]   2> 515271 T1573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6954d506 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ee95f60),segFN=segments_1,generation=1}
   [junit4]   2> 515271 T1573 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 515276 T1573 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 515276 T1573 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 515277 T1573 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 515278 T1573 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 515278 T1573 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 515279 T1573 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 515279 T1573 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 515280 T1573 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 515280 T1573 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 515289 T1573 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 515297 T1573 oass.SolrIndexSearcher.<init> Opening Searcher@3b703ff4 main
   [junit4]   2> 515298 T1573 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 515298 T1573 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 515302 T1574 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b703ff4 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 515305 T1573 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 515305 T1573 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:39965/m_jcf/v collection:collection1 shard:shard1
   [junit4]   2> 515305 T1573 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
   [junit4]   2> 515321 T1573 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 515339 T1573 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 515342 T1573 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 515342 T1573 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 515343 T1573 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:39965/m_jcf/v/collection1/
   [junit4]   2> 515343 T1573 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 515343 T1573 oasc.SyncStrategy.syncToMe http://127.0.0.1:39965/m_jcf/v/collection1/ has no replicas
   [junit4]   2> 515343 T1573 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:39965/m_jcf/v/collection1/ shard1
   [junit4]   2> 515344 T1573 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 516383 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 516404 T1552 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> 516404 T1572 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> 516404 T1559 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> 516421 T1573 oasc.ZkController.register We are http://127.0.0.1:39965/m_jcf/v/collection1/ and leader is http://127.0.0.1:39965/m_jcf/v/collection1/
   [junit4]   2> 516421 T1573 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39965/m_jcf/v
   [junit4]   2> 516421 T1573 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 516421 T1573 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 516422 T1573 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 516423 T1573 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 516425 T1530 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1
   [junit4]   2> 516425 T1530 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 516426 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 516695 T1530 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 516698 T1530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:18241
   [junit4]   2> 516699 T1530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 516699 T1530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 516700 T1530 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382
   [junit4]   2> 516700 T1530 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/'
   [junit4]   2> 516737 T1530 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/solr.xml
   [junit4]   2> 516808 T1530 oasc.CoreContainer.<init> New CoreContainer 967516286
   [junit4]   2> 516808 T1530 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/]
   [junit4]   2> 516810 T1530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 516811 T1530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 516811 T1530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 516811 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 516812 T1530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 516812 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 516813 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 516813 T1530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 516814 T1530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 516814 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 516829 T1530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 516830 T1530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:23162/solr
   [junit4]   2> 516830 T1530 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 516831 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 516834 T1586 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11e24dc6 name:ZooKeeperConnection Watcher:127.0.0.1:23162 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 516834 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 516848 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 516857 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 516860 T1588 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@186b878d name:ZooKeeperConnection Watcher:127.0.0.1:23162/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 516860 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 516869 T1530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 517873 T1530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:18241_m_jcf%2Fv
   [junit4]   2> 517875 T1530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:18241_m_jcf%2Fv
   [junit4]   2> 517878 T1559 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> 517878 T1552 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> 517879 T1572 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 517879 T1588 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 517879 T1572 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> 517880 T1559 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 517881 T1552 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 517894 T1589 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 517895 T1589 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 517896 T1589 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 517910 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 517911 T1553 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:39965_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:39965/m_jcf/v"}
   [junit4]   2> 517916 T1553 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:18241_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:18241/m_jcf/v"}
   [junit4]   2> 517916 T1553 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
   [junit4]   2> 517916 T1553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 517932 T1559 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> 517932 T1588 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> 517932 T1572 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> 517932 T1552 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> 518897 T1589 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 518897 T1589 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/collection1
   [junit4]   2> 518897 T1589 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 518898 T1589 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 518898 T1589 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 518900 T1589 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/collection1/'
   [junit4]   2> 518901 T1589 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/collection1/lib/README' to classloader
   [junit4]   2> 518902 T1589 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/collection1/lib/classes/' to classloader
   [junit4]   2> 518955 T1589 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
   [junit4]   2> 519014 T1589 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 519116 T1589 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 519129 T1589 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 519754 T1589 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 519755 T1589 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 519755 T1589 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 519768 T1589 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 519772 T1589 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 519789 T1589 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 519794 T1589 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 519798 T1589 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 519799 T1589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 519800 T1589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 519800 T1589 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 519801 T1589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 519801 T1589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 519802 T1589 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 519802 T1589 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty2-1373657113382/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty2/
   [junit4]   2> 519802 T1589 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@27d08e21
   [junit4]   2> 519803 T1589 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty2
   [junit4]   2> 519803 T1589 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty2/index/
   [junit4]   2> 519804 T1589 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty2/index' doesn't exist. Creating new index...
   [junit4]   2> 519804 T1589 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty2/index
   [junit4]   2> 519808 T1589 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5f9750a5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7f9fdfc3),segFN=segments_1,generation=1}
   [junit4]   2> 519808 T1589 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 519813 T1589 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 519813 T1589 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 519814 T1589 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 519815 T1589 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 519815 T1589 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 519815 T1589 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 519816 T1589 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 519816 T1589 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 519817 T1589 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 519826 T1589 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 519834 T1589 oass.SolrIndexSearcher.<init> Opening Searcher@27aea273 main
   [junit4]   2> 519835 T1589 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 519835 T1589 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 519840 T1590 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@27aea273 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 519842 T1589 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 519842 T1589 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:18241/m_jcf/v collection:collection1 shard:shard1
   [junit4]   2> 519842 T1589 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
   [junit4]   2> 519862 T1589 oasc.ZkController.register We are http://127.0.0.1:18241/m_jcf/v/collection1/ and leader is http://127.0.0.1:39965/m_jcf/v/collection1/
   [junit4]   2> 519863 T1589 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:18241/m_jcf/v
   [junit4]   2> 519863 T1589 oasc.ZkController.checkRecovery Core needs to recover:collection1
   [junit4]   2> 519863 T1589 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
   [junit4]   2> ASYNC  NEW_CORE C523 name=collection1 org.apache.solr.core.SolrCore@6a121fd9 url=http://127.0.0.1:18241/m_jcf/v/collection1 node=127.0.0.1:18241_m_jcf%2Fv C523_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, node_name=127.0.0.1:18241_m_jcf%2Fv, base_url=http://127.0.0.1:18241/m_jcf/v}
   [junit4]   2> 519863 T1591 C523 P18241 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
   [junit4]   2> 519864 T1589 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 519864 T1591 C523 P18241 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
   [junit4]   2> 519865 T1591 C523 P18241 oasc.ZkController.publish publishing core=collection1 state=recovering
   [junit4]   2> 519865 T1591 C523 P18241 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 519866 T1530 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1
   [junit4]   2> 519866 T1530 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 519866 T1591 C523 P18241 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 519867 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 519880 T1562 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true
   [junit4]   2> 520141 T1530 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 520144 T1530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:48097
   [junit4]   2> 520144 T1530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 520145 T1530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 520145 T1530 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825
   [junit4]   2> 520146 T1530 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/'
   [junit4]   2> 520182 T1530 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/solr.xml
   [junit4]   2> 520253 T1530 oasc.CoreContainer.<init> New CoreContainer 347250840
   [junit4]   2> 520254 T1530 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/]
   [junit4]   2> 520256 T1530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 520256 T1530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 520256 T1530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 520257 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 520257 T1530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 520258 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 520258 T1530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 520259 T1530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 520259 T1530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 520260 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 520276 T1530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 520277 T1530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:23162/solr
   [junit4]   2> 520277 T1530 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 520278 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 520281 T1603 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5b4d65c5 name:ZooKeeperConnection Watcher:127.0.0.1:23162 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 520282 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 520296 T1530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 520307 T1530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 520309 T1605 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41532413 name:ZooKeeperConnection Watcher:127.0.0.1:23162/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 520310 T1530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 520321 T1530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 520942 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 520943 T1553 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:18241_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:18241/m_jcf/v"}
   [junit4]   2> 520974 T1552 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> 520974 T1572 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> 520974 T1588 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> 520974 T1605 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> 520974 T1559 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> 521325 T1530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48097_m_jcf%2Fv
   [junit4]   2> 521331 T1530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48097_m_jcf%2Fv
   [junit4]   2> 521340 T1552 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> 521340 T1588 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> 521340 T1605 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> 521340 T1559 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> 521341 T1572 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 521342 T1572 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> 521343 T1552 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 521343 T1588 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 521343 T1605 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 521343 T1559 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 521354 T1606 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 521354 T1606 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 521356 T1606 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 521882 T1562 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true for: 2 seconds.
   [junit4]   2> 521882 T1562 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={coreNodeName=core_node2&state=recovering&nodeName=127.0.0.1:18241_m_jcf%252Fv&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=2003 
   [junit4]   2> 522503 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 522503 T1553 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:48097_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:48097/m_jcf/v"}
   [junit4]   2> 522504 T1553 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
   [junit4]   2> 522504 T1553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 522518 T1552 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> 522518 T1572 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> 522518 T1605 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> 522518 T1588 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> 522518 T1559 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> 523358 T1606 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 523358 T1606 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/collection1
   [junit4]   2> 523358 T1606 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 523359 T1606 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 523359 T1606 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 523361 T1606 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/collection1/'
   [junit4]   2> 523362 T1606 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/collection1/lib/classes/' to classloader
   [junit4]   2> 523363 T1606 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/collection1/lib/README' to classloader
   [junit4]   2> 523416 T1606 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
   [junit4]   2> 523475 T1606 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 523577 T1606 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 523589 T1606 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> ASYNC  NEW_CORE C524 name=collection1 org.apache.solr.core.SolrCore@6a121fd9 url=http://127.0.0.1:18241/m_jcf/v/collection1 node=127.0.0.1:18241_m_jcf%2Fv C524_STATE=coll:collection1 core:collection1 props:{state=recovering, core=collection1, node_name=127.0.0.1:18241_m_jcf%2Fv, base_url=http://127.0.0.1:18241/m_jcf/v}
   [junit4]   2> 523884 T1591 C524 P18241 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:39965/m_jcf/v/collection1/ core=collection1 - recoveringAfterStartup=true
   [junit4]   2> 523884 T1591 C524 P18241 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:18241/m_jcf/v START replicas=[http://127.0.0.1:39965/m_jcf/v/collection1/] nUpdates=100
   [junit4]   2> 523885 T1591 C524 P18241 oasu.PeerSync.sync WARN no frame of reference to tell of we've missed updates
   [junit4]   2> 523885 T1591 C524 P18241 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
   [junit4]   2> 523885 T1591 C524 P18241 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
   [junit4]   2> 523885 T1591 C524 P18241 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
   [junit4]   2> 523885 T1591 C524 P18241 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 523886 T1591 C524 P18241 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:39965/m_jcf/v/collection1/. core=collection1
   [junit4]   2> 523886 T1591 C524 P18241 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> ASYNC  NEW_CORE C525 name=collection1 org.apache.solr.core.SolrCore@2026d195 url=http://127.0.0.1:39965/m_jcf/v/collection1 node=127.0.0.1:39965_m_jcf%2Fv C525_STATE=coll:collection1 core:collection1 props:{state=active, core=collection1, node_name=127.0.0.1:39965_m_jcf%2Fv, base_url=http://127.0.0.1:39965/m_jcf/v, leader=true}
   [junit4]   2> 523893 T1563 C525 P39965 oasc.SolrCore.execute [collection1] webapp=/m_jcf/v path=/get params={getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=0 
   [junit4]   2> 523897 T1564 C525 P39965 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 523900 T1564 C525 P39965 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6954d506 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ee95f60),segFN=segments_1,generation=1}
   [junit4]   2> 523900 T1564 C525 P39965 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 523901 T1564 C525 P39965 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 523901 T1564 C525 P39965 oass.SolrIndexSearcher.<init> Opening Searcher@1b9f1a8b realtime
   [junit4]   2> 523902 T1564 C525 P39965 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 523902 T1564 C525 P39965 oasup.LogUpdateProcessor.finish [collection1] webapp=/m_jcf/v path=/update params={waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 5
   [junit4]   2> 523903 T1591 C524 P18241 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 523903 T1591 C524 P18241 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
   [junit4]   2> 523905 T1565 C525 P39965 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 523905 T1565 C525 P39965 oasc.SolrCore.execute [collection1] webapp=/m_jcf/v path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 523906 T1591 C524 P18241 oasc.RecoveryStrategy.replay No replay needed. core=collection1
   [junit4]   2> 523906 T1591 C524 P18241 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
   [junit4]   2> 523906 T1591 C524 P18241 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 523907 T1591 C524 P18241 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 523908 T1591 C524 P18241 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
   [junit4]   2> 524024 T1553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 524024 T1553 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":"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:18241_m_jcf%2Fv",
   [junit4]   2> 	  "base_url":"http://127.0.0.1:18241/m_jcf/v"}
   [junit4]   2> 524037 T1552 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> 524037 T1572 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> 524037 T1588 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> 524037 T1605 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> 524037 T1559 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> 524210 T1606 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 524210 T1606 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 524211 T1606 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 524224 T1606 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 524228 T1606 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 524244 T1606 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 524249 T1606 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 524254 T1606 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 524255 T1606 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 524255 T1606 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 524256 T1606 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 524257 T1606 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 524257 T1606 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 524257 T1606 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 524258 T1606 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build/solr-core/test/J1/./org.apache.solr.cloud.AliasIntegrationTest-jetty3-1373657116825/collection1/, dataDir=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty3/
   [junit4]   2> 524258 T1606 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@27d08e21
   [junit4]   2> 524259 T1606 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty3
   [junit4]   2> 524259 T1606 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty3/index/
   [junit4]   2> 524259 T1606 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty3/index' doesn't exist. Creating new index...
   [junit4]   2> 524260 T1606 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.AliasIntegrationTest-1373657104911/jetty3/index
   [junit4]   2> 524263 T1606 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5dc2ce3b lockFactory=org.apache.lucene.store.NativeFSLockFactory@2fb619cf),segFN=segments_1,generation=1}
   [junit4]   2> 524264 T1606 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 524269 T1606 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 524269 T1606 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 524270 T1606 oasup.U

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

ent.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:1146)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	        at java.lang.Thread.run(Thread.java:679)
   [junit4]   2> NOTE: test params are: codec=Lucene42: {timestamp=PostingsFormat(name=Direct), range_facet_l=PostingsFormat(name=Direct), id=PostingsFormat(name=NestedPulsing), range_facet_sl=PostingsFormat(name=Asserting), a_t=PostingsFormat(name=Direct), text=PostingsFormat(name=Asserting), range_facet_si=PostingsFormat(name=Lucene41WithOrds), _version_=PostingsFormat(name=Direct), other_tl1=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=Lucene41WithOrds), a_si=PostingsFormat(name=Lucene41WithOrds), intDefault=PostingsFormat(name=Direct)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=sr_RS, timezone=Europe/Helsinki
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=2,free=283207216,total=431226880
   [junit4]   2> NOTE: All tests run in this JVM: [TestReload, TestBinaryResponseWriter, SolrCoreCheckLockOnStartupTest, RequestHandlersTest, CoreContainerCoreInitFailuresTest, TestDynamicFieldResource, TermVectorComponentDistributedTest, SolrTestCaseJ4Test, TestManagedSchema, TestBinaryField, TestIndexSearcher, TestStressLucene, TestLuceneMatchVersion, UniqFieldsUpdateProcessorFactoryTest, CachingDirectoryFactoryTest, TestJmxMonitoredMap, TestAtomicUpdateErrorCases, TestSuggestSpellingConverter, DirectSolrSpellCheckerTest, TestSchemaResource, TestDynamicFieldCollectionResource, TestAnalyzedSuggestions, OutputWriterTest, ChaosMonkeySafeLeaderTest, OpenExchangeRatesOrgProviderTest, TestDefaultSearchFieldResource, TestDistributedSearch, DefaultValueUpdateProcessorTest, RequiredFieldsTest, CurrencyFieldOpenExchangeTest, TestNumberUtils, IndexBasedSpellCheckerTest, TestClassNameShortening, TestRandomFaceting, DocumentAnalysisRequestHandlerTest, SolrPluginUtilsTest, CopyFieldTest, TestIndexingPerformance, TestCollationField, TestUpdate, HdfsCollectionsAPIDistributedZkTest, TestFieldResource, IndexSchemaRuntimeFieldTest, DOMUtilTest, StandardRequestHandlerTest, TestQuerySenderNoQuery, SolrRequestParserTest, TestDistributedGrouping, TestQueryTypes, TestJmxIntegration, TestFiltering, TestAddFieldRealTimeGet, XsltUpdateRequestHandlerTest, SignatureUpdateProcessorFactoryTest, HighlighterConfigTest, TestCoreDiscovery, TestRTGBase, AliasIntegrationTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AliasIntegrationTest -Dtests.seed=60103E8421F9FCF5 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_RS -Dtests.timezone=Europe/Helsinki -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J1 | 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=1704, name=recoveryCmdExecutor-942-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
   [junit4]    >         at java.net.PlainSocketImpl.socketConnect(Native Method)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:327)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:193)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:180)
   [junit4]    >         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:384)
   [junit4]    >         at java.net.Socket.connect(Socket.java:546)
   [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:1146)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:679)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([60103E8421F9FCF5]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=1704, name=recoveryCmdExecutor-942-thread-1, state=RUNNABLE, group=TGRP-AliasIntegrationTest]
   [junit4]    >         at java.net.PlainSocketImpl.socketConnect(Native Method)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:327)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:193)
   [junit4]    >         at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:180)
   [junit4]    >         at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:384)
   [junit4]    >         at java.net.Socket.connect(Socket.java:546)
   [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:1146)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]    >         at java.lang.Thread.run(Thread.java:679)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([60103E8421F9FCF5]:0)
   [junit4] Completed on J1 in 122.21s, 1 test, 2 errors <<< FAILURES!

[...truncated 719 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/build.xml:395: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/build.xml:375: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/build.xml:181: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/solr/common-build.xml:449: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/lucene/common-build.xml:1250: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-Java6/lucene/common-build.xml:893: There were test failures: 317 suites, 1337 tests, 2 suite-level errors, 716 ignored

Total time: 42 minutes 0 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