You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/07/24 20:13:52 UTC
[JENKINS] Lucene-Solr-Tests-4.4-Java6 - Build # 48 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.4-Java6/48/
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZkTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.BasicDistributedZkTest: 1) Thread[id=3020, name=recoveryCmdExecutor-1843-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest] 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.BasicDistributedZkTest:
1) Thread[id=3020, name=recoveryCmdExecutor-1843-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
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([22A906F67C1915FE]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZkTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=3020, name=recoveryCmdExecutor-1843-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest] 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=3020, name=recoveryCmdExecutor-1843-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
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([22A906F67C1915FE]:0)
Build Log:
[...truncated 9128 lines...]
[junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4] 2> 1312853 T2530 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /hdus/si
[junit4] 2> 1312858 T2530 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-BasicDistributedZkTest-1374689218973
[junit4] 2> 1312860 T2530 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 1312861 T2531 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1312962 T2530 oasc.ZkTestServer.run start zk server on port:48814
[junit4] 2> 1312963 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1312992 T2537 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@720ff764 name:ZooKeeperConnection Watcher:127.0.0.1:48814 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1312992 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1312992 T2530 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1313008 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1313010 T2539 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d1ddee0 name:ZooKeeperConnection Watcher:127.0.0.1:48814/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1313011 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1313011 T2530 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1313016 T2530 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1313024 T2530 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1313027 T2530 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1313030 T2530 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> 1313030 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1313041 T2530 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> 1313042 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1313160 T2530 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> 1313160 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1313164 T2530 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> 1313164 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1313167 T2530 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> 1313168 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1313171 T2530 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> 1313172 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1313175 T2530 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> 1313175 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1313178 T2530 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> 1313179 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1313188 T2530 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> 1313189 T2530 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1313468 T2530 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1313474 T2530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:48817
[junit4] 2> 1313475 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1313476 T2530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1313476 T2530 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.BasicDistributedZkTest-controljetty-1374689219311
[junit4] 2> 1313476 T2530 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.BasicDistributedZkTest-controljetty-1374689219311/'
[junit4] 2> 1313513 T2530 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.BasicDistributedZkTest-controljetty-1374689219311/solr.xml
[junit4] 2> 1313590 T2530 oasc.CoreContainer.<init> New CoreContainer 1080724525
[junit4] 2> 1313591 T2530 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.BasicDistributedZkTest-controljetty-1374689219311/]
[junit4] 2> 1313593 T2530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1313593 T2530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1313594 T2530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1313595 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1313595 T2530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1313595 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1313596 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1313596 T2530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1313597 T2530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1313597 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1313612 T2530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1313613 T2530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:48814/solr
[junit4] 2> 1313613 T2530 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1313615 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1313617 T2550 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e2749ae name:ZooKeeperConnection Watcher:127.0.0.1:48814 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1313618 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1313629 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1313639 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1313641 T2552 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d305ba9 name:ZooKeeperConnection Watcher:127.0.0.1:48814/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1313641 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1313644 T2530 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1313653 T2530 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1313657 T2530 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1313659 T2530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48817_hdus%2Fsi
[junit4] 2> 1313660 T2530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48817_hdus%2Fsi
[junit4] 2> 1313670 T2530 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1313687 T2530 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1313690 T2530 oasc.Overseer.start Overseer (id=90091632655073283-127.0.0.1:48817_hdus%2Fsi-n_0000000000) starting
[junit4] 2> 1313694 T2530 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1313707 T2554 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1313708 T2530 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1313710 T2530 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1313712 T2530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1313714 T2553 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1313718 T2555 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1313718 T2555 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1313719 T2555 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1315217 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1315218 T2553 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:48817_hdus%2Fsi",
[junit4] 2> "base_url":"http://127.0.0.1:48817/hdus/si"}
[junit4] 2> 1315218 T2553 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4] 2> 1315219 T2553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1315234 T2552 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> 1315722 T2555 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1315722 T2555 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.BasicDistributedZkTest-controljetty-1374689219311/collection1
[junit4] 2> 1315722 T2555 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 1315723 T2555 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1315723 T2555 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1315725 T2555 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.BasicDistributedZkTest-controljetty-1374689219311/collection1/'
[junit4] 2> 1315726 T2555 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.BasicDistributedZkTest-controljetty-1374689219311/collection1/lib/README' to classloader
[junit4] 2> 1315727 T2555 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.BasicDistributedZkTest-controljetty-1374689219311/collection1/lib/classes/' to classloader
[junit4] 2> 1315780 T2555 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4] 2> 1315841 T2555 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1315944 T2555 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1315954 T2555 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1316564 T2555 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1316564 T2555 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1316565 T2555 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1316578 T2555 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1316582 T2555 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1316595 T2555 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1316600 T2555 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1316605 T2555 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1316606 T2555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1316606 T2555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1316606 T2555 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1316607 T2555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1316607 T2555 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1316608 T2555 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1316608 T2555 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.BasicDistributedZkTest-controljetty-1374689219311/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/control/data/
[junit4] 2> 1316608 T2555 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@452e7234
[junit4] 2> 1316609 T2555 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/control/data
[junit4] 2> 1316609 T2555 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/control/data/index/
[junit4] 2> 1316610 T2555 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 1316610 T2555 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/control/data/index
[junit4] 2> 1316615 T2555 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@547f528f lockFactory=org.apache.lucene.store.NativeFSLockFactory@f76cd45),segFN=segments_1,generation=1}
[junit4] 2> 1316615 T2555 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1316619 T2555 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1316619 T2555 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1316620 T2555 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1316620 T2555 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1316621 T2555 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1316621 T2555 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1316621 T2555 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1316622 T2555 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1316622 T2555 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1316632 T2555 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1316639 T2555 oass.SolrIndexSearcher.<init> Opening Searcher@35d5501d main
[junit4] 2> 1316641 T2555 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1316641 T2555 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1316644 T2556 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@35d5501d main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1316646 T2555 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1316646 T2555 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48817/hdus/si collection:control_collection shard:shard1
[junit4] 2> 1316646 T2555 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4] 2> 1316663 T2555 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1316680 T2555 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1316683 T2555 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1316683 T2555 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1316684 T2555 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48817/hdus/si/collection1/
[junit4] 2> 1316684 T2555 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1316684 T2555 oasc.SyncStrategy.syncToMe http://127.0.0.1:48817/hdus/si/collection1/ has no replicas
[junit4] 2> 1316684 T2555 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48817/hdus/si/collection1/ shard1
[junit4] 2> 1316685 T2555 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1316739 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1316756 T2552 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> 1316792 T2555 oasc.ZkController.register We are http://127.0.0.1:48817/hdus/si/collection1/ and leader is http://127.0.0.1:48817/hdus/si/collection1/
[junit4] 2> 1316792 T2555 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48817/hdus/si
[junit4] 2> 1316792 T2555 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1316792 T2555 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 1316793 T2555 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1316794 T2555 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1316795 T2530 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> 1316796 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1316796 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1316806 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4] 2> 1316807 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1316809 T2559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ea1e341 name:ZooKeeperConnection Watcher:127.0.0.1:48814/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1316809 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1316811 T2530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1316813 T2530 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 1317086 T2530 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1317088 T2530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:48826
[junit4] 2> 1317089 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1317090 T2530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1317090 T2530 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.BasicDistributedZkTest-jetty1-1374689222927
[junit4] 2> 1317091 T2530 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.BasicDistributedZkTest-jetty1-1374689222927/'
[junit4] 2> 1317134 T2530 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.BasicDistributedZkTest-jetty1-1374689222927/solr.xml
[junit4] 2> 1317206 T2530 oasc.CoreContainer.<init> New CoreContainer 58093936
[junit4] 2> 1317206 T2530 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.BasicDistributedZkTest-jetty1-1374689222927/]
[junit4] 2> 1317208 T2530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1317209 T2530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1317209 T2530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1317210 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1317210 T2530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1317211 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1317211 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1317211 T2530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1317212 T2530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1317212 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1317229 T2530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1317230 T2530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:48814/solr
[junit4] 2> 1317230 T2530 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1317231 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1317307 T2570 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@551171d6 name:ZooKeeperConnection Watcher:127.0.0.1:48814 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1317307 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1317310 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1317322 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1317323 T2572 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7cf99a4d name:ZooKeeperConnection Watcher:127.0.0.1:48814/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1317324 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1317368 T2530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1318269 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1318270 T2553 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:48817_hdus%2Fsi",
[junit4] 2> "base_url":"http://127.0.0.1:48817/hdus/si"}
[junit4] 2> 1318285 T2559 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> 1318285 T2552 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> 1318285 T2572 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> 1318371 T2530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48826_hdus%2Fsi
[junit4] 2> 1318373 T2530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48826_hdus%2Fsi
[junit4] 2> 1318375 T2559 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> 1318375 T2552 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> 1318375 T2572 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1318376 T2572 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> 1318376 T2559 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1318376 T2552 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1318388 T2573 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1318388 T2573 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1318390 T2573 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1319790 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1319791 T2553 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":"2",
[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:48826_hdus%2Fsi",
[junit4] 2> "base_url":"http://127.0.0.1:48826/hdus/si"}
[junit4] 2> 1319791 T2553 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4] 2> 1319791 T2553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1319808 T2559 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> 1319808 T2552 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> 1319808 T2572 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> 1320392 T2573 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1320392 T2573 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.BasicDistributedZkTest-jetty1-1374689222927/collection1
[junit4] 2> 1320392 T2573 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1320393 T2573 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1320393 T2573 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1320394 T2573 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.BasicDistributedZkTest-jetty1-1374689222927/collection1/'
[junit4] 2> 1320395 T2573 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.BasicDistributedZkTest-jetty1-1374689222927/collection1/lib/classes/' to classloader
[junit4] 2> 1320396 T2573 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.BasicDistributedZkTest-jetty1-1374689222927/collection1/lib/README' to classloader
[junit4] 2> 1320433 T2573 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4] 2> 1320474 T2573 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1320576 T2573 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1320584 T2573 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1321008 T2573 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1321008 T2573 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1321009 T2573 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1321017 T2573 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1321020 T2573 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1321030 T2573 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1321033 T2573 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1321042 T2573 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1321043 T2573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1321043 T2573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1321043 T2573 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1321044 T2573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1321044 T2573 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1321044 T2573 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1321044 T2573 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.BasicDistributedZkTest-jetty1-1374689222927/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty1/
[junit4] 2> 1321045 T2573 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@452e7234
[junit4] 2> 1321045 T2573 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty1
[junit4] 2> 1321045 T2573 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty1/index/
[junit4] 2> 1321046 T2573 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 1321046 T2573 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty1/index
[junit4] 2> 1321048 T2573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4b1824d6 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2deedef3),segFN=segments_1,generation=1}
[junit4] 2> 1321049 T2573 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1321052 T2573 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1321052 T2573 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1321052 T2573 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1321053 T2573 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1321053 T2573 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1321053 T2573 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1321054 T2573 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1321054 T2573 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1321054 T2573 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1321061 T2573 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1321065 T2573 oass.SolrIndexSearcher.<init> Opening Searcher@6130c5ea main
[junit4] 2> 1321066 T2573 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1321067 T2573 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1321069 T2574 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6130c5ea main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1321071 T2573 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1321071 T2573 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48826/hdus/si collection:collection1 shard:shard1
[junit4] 2> 1321071 T2573 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4] 2> 1321082 T2573 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 1321102 T2573 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1321103 T2573 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1321104 T2573 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1321104 T2573 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48826/hdus/si/collection1/
[junit4] 2> 1321104 T2573 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1321104 T2573 oasc.SyncStrategy.syncToMe http://127.0.0.1:48826/hdus/si/collection1/ has no replicas
[junit4] 2> 1321104 T2573 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48826/hdus/si/collection1/ shard1
[junit4] 2> 1321104 T2573 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1321313 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1321331 T2552 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> 1321331 T2572 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> 1321331 T2559 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> 1321364 T2573 oasc.ZkController.register We are http://127.0.0.1:48826/hdus/si/collection1/ and leader is http://127.0.0.1:48826/hdus/si/collection1/
[junit4] 2> 1321364 T2573 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48826/hdus/si
[junit4] 2> 1321364 T2573 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1321364 T2573 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 1321364 T2573 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1321366 T2573 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1321367 T2530 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> 1321367 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1321368 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1321552 T2530 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1321555 T2530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:38570
[junit4] 2> 1321555 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1321556 T2530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1321556 T2530 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.BasicDistributedZkTest-jetty2-1374689227488
[junit4] 2> 1321556 T2530 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.BasicDistributedZkTest-jetty2-1374689227488/'
[junit4] 2> 1321581 T2530 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.BasicDistributedZkTest-jetty2-1374689227488/solr.xml
[junit4] 2> 1321629 T2530 oasc.CoreContainer.<init> New CoreContainer 485313799
[junit4] 2> 1321630 T2530 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.BasicDistributedZkTest-jetty2-1374689227488/]
[junit4] 2> 1321631 T2530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1321631 T2530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1321632 T2530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1321632 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1321632 T2530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1321632 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1321633 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1321633 T2530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1321633 T2530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1321634 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1321644 T2530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1321645 T2530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:48814/solr
[junit4] 2> 1321645 T2530 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1321646 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1321649 T2586 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f5c1567 name:ZooKeeperConnection Watcher:127.0.0.1:48814 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1321649 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1321665 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1321673 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1321674 T2588 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@26570180 name:ZooKeeperConnection Watcher:127.0.0.1:48814/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1321674 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1321683 T2530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1322686 T2530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38570_hdus%2Fsi
[junit4] 2> 1322692 T2530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38570_hdus%2Fsi
[junit4] 2> 1322697 T2552 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> 1322697 T2559 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> 1322698 T2572 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1322698 T2588 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1322698 T2572 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> 1322700 T2552 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1322700 T2559 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1322710 T2589 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1322710 T2589 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1322738 T2589 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1322835 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1322836 T2553 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":"2",
[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:48826_hdus%2Fsi",
[junit4] 2> "base_url":"http://127.0.0.1:48826/hdus/si"}
[junit4] 2> 1322842 T2553 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "numShards":"2",
[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:38570_hdus%2Fsi",
[junit4] 2> "base_url":"http://127.0.0.1:38570/hdus/si"}
[junit4] 2> 1322842 T2553 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 1322842 T2553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1322862 T2559 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> 1322862 T2572 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> 1322862 T2552 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> 1322862 T2588 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> 1323741 T2589 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1323741 T2589 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.BasicDistributedZkTest-jetty2-1374689227488/collection1
[junit4] 2> 1323741 T2589 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1323742 T2589 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1323742 T2589 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1323744 T2589 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.BasicDistributedZkTest-jetty2-1374689227488/collection1/'
[junit4] 2> 1323745 T2589 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.BasicDistributedZkTest-jetty2-1374689227488/collection1/lib/classes/' to classloader
[junit4] 2> 1323746 T2589 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.BasicDistributedZkTest-jetty2-1374689227488/collection1/lib/README' to classloader
[junit4] 2> 1323801 T2589 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4] 2> 1323861 T2589 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1323964 T2589 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1323975 T2589 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1324587 T2589 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1324588 T2589 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1324588 T2589 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1324597 T2589 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1324599 T2589 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1324609 T2589 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1324613 T2589 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1324616 T2589 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1324616 T2589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1324616 T2589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1324617 T2589 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1324617 T2589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1324618 T2589 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1324618 T2589 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1324618 T2589 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.BasicDistributedZkTest-jetty2-1374689227488/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty2/
[junit4] 2> 1324618 T2589 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@452e7234
[junit4] 2> 1324619 T2589 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty2
[junit4] 2> 1324619 T2589 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty2/index/
[junit4] 2> 1324619 T2589 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 1324619 T2589 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty2/index
[junit4] 2> 1324622 T2589 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43c21946 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5cbf424a),segFN=segments_1,generation=1}
[junit4] 2> 1324622 T2589 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1324625 T2589 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1324625 T2589 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1324626 T2589 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1324626 T2589 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1324627 T2589 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1324627 T2589 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1324627 T2589 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1324627 T2589 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1324628 T2589 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1324634 T2589 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1324639 T2589 oass.SolrIndexSearcher.<init> Opening Searcher@29bbfc39 main
[junit4] 2> 1324639 T2589 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1324640 T2589 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1324643 T2590 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29bbfc39 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1324644 T2589 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1324644 T2589 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:38570/hdus/si collection:collection1 shard:shard2
[junit4] 2> 1324644 T2589 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4] 2> 1324655 T2589 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 1324673 T2589 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 1324676 T2589 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1324676 T2589 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1324676 T2589 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:38570/hdus/si/collection1/
[junit4] 2> 1324676 T2589 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1324677 T2589 oasc.SyncStrategy.syncToMe http://127.0.0.1:38570/hdus/si/collection1/ has no replicas
[junit4] 2> 1324677 T2589 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:38570/hdus/si/collection1/ shard2
[junit4] 2> 1324677 T2589 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 1325870 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1325888 T2559 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> 1325888 T2552 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> 1325888 T2588 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> 1325888 T2572 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> 1325906 T2589 oasc.ZkController.register We are http://127.0.0.1:38570/hdus/si/collection1/ and leader is http://127.0.0.1:38570/hdus/si/collection1/
[junit4] 2> 1325906 T2589 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38570/hdus/si
[junit4] 2> 1325906 T2589 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1325906 T2589 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 1325907 T2589 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1325908 T2589 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1325909 T2530 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> 1325910 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1325910 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1326201 T2530 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1326205 T2530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:27877
[junit4] 2> 1326205 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1326206 T2530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1326206 T2530 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.BasicDistributedZkTest-jetty3-1374689232034
[junit4] 2> 1326207 T2530 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.BasicDistributedZkTest-jetty3-1374689232034/'
[junit4] 2> 1326244 T2530 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.BasicDistributedZkTest-jetty3-1374689232034/solr.xml
[junit4] 2> 1326318 T2530 oasc.CoreContainer.<init> New CoreContainer 979284484
[junit4] 2> 1326318 T2530 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.BasicDistributedZkTest-jetty3-1374689232034/]
[junit4] 2> 1326320 T2530 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1326321 T2530 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1326321 T2530 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1326322 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1326322 T2530 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1326322 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1326323 T2530 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1326323 T2530 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1326324 T2530 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1326324 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1326341 T2530 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1326342 T2530 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:48814/solr
[junit4] 2> 1326342 T2530 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1326343 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1326346 T2602 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ef9428c name:ZooKeeperConnection Watcher:127.0.0.1:48814 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1326347 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1326359 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1326367 T2530 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1326369 T2604 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18427ede name:ZooKeeperConnection Watcher:127.0.0.1:48814/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1326370 T2530 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1326381 T2530 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1327386 T2530 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:27877_hdus%2Fsi
[junit4] 2> 1327397 T2530 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:27877_hdus%2Fsi
[junit4] 2> 1327405 T2559 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> 1327405 T2552 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> 1327406 T2572 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1327406 T2604 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1327405 T2588 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> 1327406 T2572 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> 1327409 T2559 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1327409 T2553 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1327409 T2588 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1327410 T2553 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard":"shard2",
[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:38570_hdus%2Fsi",
[junit4] 2> "base_url":"http://127.0.0.1:38570/hdus/si"}
[junit4] 2> 1327413 T2605 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1327413 T2605 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1327421 T2605 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1327428 T2553 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "numShards":"2",
[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:27877_hdus%2Fsi",
[junit4] 2> "base_url":"http://127.0.0.1:27877/hdus/si"}
[junit4] 2> 1327428 T2553 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 1327428 T2553 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1327439 T2604 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> 1327439 T2559 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> 1327439 T2588 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> 1327439 T2572 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> 1327445 T2552 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1327445 T2552 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> 1328422 T2605 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1328422 T2605 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.BasicDistributedZkTest-jetty3-1374689232034/collection1
[junit4] 2> 1328422 T2605 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1328423 T2605 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1328423 T2605 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1328424 T2605 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.BasicDistributedZkTest-jetty3-1374689232034/collection1/'
[junit4] 2> 1328425 T2605 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.BasicDistributedZkTest-jetty3-1374689232034/collection1/lib/classes/' to classloader
[junit4] 2> 1328426 T2605 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.BasicDistributedZkTest-jetty3-1374689232034/collection1/lib/README' to classloader
[junit4] 2> 1328462 T2605 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_44
[junit4] 2> 1328502 T2605 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1328605 T2605 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1328613 T2605 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1329104 T2605 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1329105 T2605 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1329106 T2605 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1329118 T2605 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1329123 T2605 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1329135 T2605 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1329139 T2605 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1329144 T2605 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1329145 T2605 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1329145 T2605 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1329145 T2605 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1329146 T2605 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1329146 T2605 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1329147 T2605 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1329147 T2605 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.BasicDistributedZkTest-jetty3-1374689232034/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty3/
[junit4] 2> 1329147 T2605 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@452e7234
[junit4] 2> 1329148 T2605 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty3
[junit4] 2> 1329148 T2605 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty3/index/
[junit4] 2> 1329149 T2605 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 1329149 T2605 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZkTest-1374689218972/jetty3/index
[junit4] 2> 1329152 T2605 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7238b7d8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2f4f674f),segFN=segments_1,generation=1}
[junit4] 2> 1329153 T2605 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1329157 T2605 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1329158 T2605 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1329158 T2605 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1329159 T2605 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1329159 T2605 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1329160 T2605 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1329160 T2605 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1329161 T2605 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1329161 T2605 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1329171 T2605 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1329178 T2605 oass.SolrIndexSearcher.<init> Opening Searcher@75f32711 main
[junit4] 2> 1329179 T2605 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1329179 T2605 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1329183 T2606 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@75f32711 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1329185 T2605 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1329185 T2605 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:27877/hdus/si collection:collection1 shard:shard1
[junit4] 2> 1329186 T2605 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4] 2> 1329203 T2605 oasc.ZkController.register We are http://127.0.0.1:27877/hdus/si/collection1/ and leader is http://127.0.0.1:48826/hdus/si/collection1/
[junit4] 2> 1329204 T2605 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:27877/hdus/si
[junit4] 2> 1329204 T2605 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 1329204 T2605 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C1645 name=collection1 org.apache.solr.core.SolrCore@49913b5a url=http://127.0.0.1:27877/hdus/si/collection1 node=127.0.0.1:27877_hdus%2Fsi C1645_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, node_name=127.0.0.1:27877_hdus%2Fsi, base_url=http://127.0.0.1:27877/hdus/si}
[junit4] 2> 1329204 T2607 C1645 P27877 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 1329205 T2607 C1645 P27877 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 1329205 T2605 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1329205 T2607 C1645 P27877 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 1329205 T2607 C1645 P27877 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1329206 T2530 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> 1329206 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1329207 T2607 C1645 P27877 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1329207 T2530 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1329220 T2562 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 1329392 T2530 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1329395 T2530 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:64727
[junit4] 2> 1329395 T2530 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1329396 T2530 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1329396 T2530 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.BasicDistributedZkTest-jetty4-1374689235330
[junit4] 2> 1329396 T2530 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.BasicDistributedZkTest-jetty4-1374689235330/'
[junit4] 2> 1329421 T2530 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.BasicDistributedZkTest-jetty4-1374689235330/solr.xml
[junit4] 2> 1329470 T2530 oasc.CoreContainer.<init> New CoreContainer 1497121797
[junit4] 2> 1329470 T2530 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.4-J
[...truncated too long message...]
ue), timestamp=PostingsFormat(name=Asserting), id=PostingsFormat(name=MockSep), range_facet_sl=PostingsFormat(name=MockSep), range_facet_si=PostingsFormat(name=Asserting), oddField_s=PostingsFormat(name=MockSep), sequence_i=PostingsFormat(name=MockSep), name=PostingsFormat(name=MockSep), foo_i=PostingsFormat(name=Memory doPackFST= true), regex_dup_B_s=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=Asserting), n_tf1=PostingsFormat(name=Asserting), n_dt1=PostingsFormat(name=Memory doPackFST= true), genre_s=PostingsFormat(name=MockSep), author_t=PostingsFormat(name=Memory doPackFST= true), range_facet_l=PostingsFormat(name=Asserting), n_ti1=PostingsFormat(name=MockSep), text=PostingsFormat(name=Lucene41WithOrds), _version_=PostingsFormat(name=Memory doPackFST= true), val_i=PostingsFormat(name=MockSep), SubjectTerms_mfacet=PostingsFormat(name=Memory doPackFST= true), series_t=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=Asserting), n_tdt1=PostingsFormat(name=Memory doPackFST= true), regex_dup_A_s=PostingsFormat(name=Lucene41WithOrds), price=PostingsFormat(name=Lucene41WithOrds), other_tl1=PostingsFormat(name=Memory doPackFST= true), n_l1=PostingsFormat(name=Asserting), a_si=PostingsFormat(name=Memory doPackFST= true), inStock=PostingsFormat(name=Lucene41WithOrds)}, docValues:{}, sim=DefaultSimilarity, locale=ar_MA, timezone=America/Phoenix
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=2,free=184344928,total=449970176
[junit4] 2> NOTE: All tests run in this JVM: [TestSchemaVersionResource, TestAddFieldRealTimeGet, TestCollationField, TestArbitraryIndexDir, ShardRoutingCustomTest, TestComponentsName, OutputWriterTest, IndexReaderFactoryTest, RequiredFieldsTest, TestCopyFieldCollectionResource, DistanceFunctionTest, HdfsSyncSliceTest, SolrIndexConfigTest, OpenCloseCoreStressTest, TestAtomicUpdateErrorCases, TestIndexSearcher, TestManagedSchema, ChaosMonkeySafeLeaderTest, TestFoldingMultitermQuery, TestWordDelimiterFilterFactory, TestRangeQuery, TestBinaryField, SolrCmdDistributorTest, SpellCheckComponentTest, RequestHandlersTest, BasicZkTest, TestSerializedLuceneMatchVersion, FieldAnalysisRequestHandlerTest, AliasIntegrationTest, NotRequiredUniqueKeyTest, TestCollationKeyRangeQueries, TestCoreDiscovery, SimplePostToolTest, CollectionsAPIDistributedZkTest, BadIndexSchemaTest, DocValuesMultiTest, TestHashPartitioner, SignatureUpdateProcessorFactoryTest, TestDynamicFieldCollectionResource, TestPropInject, TestPostingsSolrHighlighter, EchoParamsTest, TestReload, HighlighterConfigTest, TestSolrJ, SpellPossibilityIteratorTest, TestStressReorder, XsltUpdateRequestHandlerTest, SolrRequestParserTest, PreAnalyzedUpdateProcessorTest, TestJmxMonitoredMap, XmlUpdateRequestHandlerTest, TestMergePolicyConfig, SolrTestCaseJ4Test, ConvertedLegacyTest, TestSolrXMLSerializer, PathHierarchyTokenizerFactoryTest, DirectSolrSpellCheckerTest, TestDistributedSearch, OpenExchangeRatesOrgProviderTest, ZkNodePropsTest, TestFiltering, ZkSolrClientTest, DisMaxRequestHandlerTest, TestRecovery, MultiTermTest, TestLuceneMatchVersion, TestSchemaNameResource, TestElisionMultitermQuery, BasicDistributedZk2Test, CoreAdminCreateDiscoverTest, FullSolrCloudDistribCmdsTest, HdfsCollectionsAPIDistributedZkTest, AssignTest, TestQuerySenderNoQuery, BasicDistributedZkTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicDistributedZkTest -Dtests.seed=22A906F67C1915FE -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_MA -Dtests.timezone=America/Phoenix -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | BasicDistributedZkTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.BasicDistributedZkTest:
[junit4] > 1) Thread[id=3020, name=recoveryCmdExecutor-1843-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
[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([22A906F67C1915FE]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=3020, name=recoveryCmdExecutor-1843-thread-1, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
[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([22A906F67C1915FE]:0)
[junit4] Completed on J1 in 205.42s, 1 test, 2 errors <<< FAILURES!
[...truncated 580 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, 640 ignored (5 assumptions)
Total time: 48 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure