Yes, I found this in the regionserver log:
2010-11-12 18:13:29,094 WARN
org.apache.hadoop.hbase.regionserver.HRegionServer: Unable to connect to
master. Retrying. Error was:
java.net.ConnectException: Connection refused
at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:574)
at
org.apache.hadoop.net.SocketIOWithTimeout.connect(SocketIOWithTimeout.java:206)
at org.apache.hadoop.net.NetUtils.connect(NetUtils.java:404)
at
org.apache.hadoop.hbase.ipc.HBaseClient$Connection.setupIOstreams(HBaseClient.java:308)
at
org.apache.hadoop.hbase.ipc.HBaseClient.getConnection(HBaseClient.java:844)
at org.apache.hadoop.hbase.ipc.HBaseClient.call(HBaseClient.java:716)
at
org.apache.hadoop.hbase.ipc.HBaseRPC$Invoker.invoke(HBaseRPC.java:333)
at $Proxy0.getProtocolVersion(Unknown Source)
at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:489)
at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:465)
at org.apache.hadoop.hbase.ipc.HBaseRPC.getProxy(HBaseRPC.java:512)
at org.apache.hadoop.hbase.ipc.HBaseRPC.waitForProxy(HBaseRPC.java:423)
at
org.apache.hadoop.hbase.regionserver.HRegionServer.getMaster(HRegionServer.java:1299)
at
org.apache.hadoop.hbase.regionserver.HRegionServer.reportForDuty(HRegionServer.java:1317)
at
org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:434)
at java.lang.Thread.run(Thread.java:662)
But master is running fine, and I am able to ping the nodes from each other.
I found this in the master's log:
2010-11-12 12:47:38,908 INFO org.apache.hadoop.hbase.master.ServerManager: 1
region servers, 0 dead, average load 4.0
2010-11-12 12:48:25,685 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scanning meta region {server: 127.0.0.1:60020,
regionname: -ROOT-,,0, startKey: <>}
2010-11-12 12:48:25,694 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scan of 1 row(s) of meta region {server:
127.0.0.1:60020, regionname: -ROOT-,,0, startKey: <>} complete
2010-11-12 12:48:26,672 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scanning meta region {server: 127.0.0.1:60020,
regionname: .META.,,1, startKey: <>}
2010-11-12 12:48:26,682 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scan of 2 row(s) of meta region {server:
127.0.0.1:60020, regionname: .META.,,1, startKey: <>} complete
2010-11-12 12:48:26,682 INFO org.apache.hadoop.hbase.master.BaseScanner: All
1 .META. region(s) scanned
2010-11-12 12:48:38,908 INFO org.apache.hadoop.hbase.master.ServerManager: 1
region servers, 0 dead, average load 4.0
2010-11-12 12:49:25,686 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scanning meta region {server: 127.0.0.1:60020,
regionname: -ROOT-,,0, startKey: <>}
2010-11-12 12:49:25,694 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scan of 1 row(s) of meta region {server:
127.0.0.1:60020, regionname: -ROOT-,,0, startKey: <>} complete
2010-11-12 12:49:26,672 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scanning meta region {server: 127.0.0.1:60020,
regionname: .META.,,1, startKey: <>}
2010-11-12 12:49:26,682 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scan of 2 row(s) of meta region {server:
127.0.0.1:60020, regionname: .META.,,1, startKey: <>} complete
2010-11-12 12:49:26,682 INFO org.apache.hadoop.hbase.master.BaseScanner: All
1 .META. region(s) scanned
2010-11-12 12:49:38,908 INFO org.apache.hadoop.hbase.master.ServerManager: 1
region servers, 0 dead, average load 4.0
2010-11-12 12:50:25,685 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scanning meta region {server: 127.0.0.1:60020,
regionname: -ROOT-,,0, startKey: <>}
2010-11-12 12:50:25,694 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scan of 1 row(s) of meta region {server:
127.0.0.1:60020, regionname: -ROOT-,,0, startKey: <>} complete
This is missing something, because I remember earlier when everything was
working fine I used to get 3 regionservers instead of 1.
A sample from the earlier log when everything was working fine:
2010-11-01 16:00:13,103 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scanning meta region {server: 192.168.0.134:60020,
regionname: -ROOT-,,0, startKey: <>}
2010-11-01 16:00:13,111 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scan of 1 row(s) of meta region {server:
192.168.0.134:60020, regionname: -ROOT-,,0, startKey: <>} complete
2010-11-01 16:00:15,702 WARN org.apache.hadoop.ipc.HBaseServer: Incorrect
header or version mismatch from 192.168.0.158:35240 got version 47 expected
version 3
2010-11-01 16:00:20,440 DEBUG
org.apache.hadoop.hbase.client.HConnectionManager$TableServers: Cache hit
for row <> in tableName .META.: location server 192.168.0.99:60020, location
region name .META.,,1
2010-11-01 16:00:59,677 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scanning meta region {server: 192.168.0.99:60020,
regionname: .META.,,1, startKey: <>}
2010-11-01 16:00:59,688 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scan of 1 row(s) of meta region {server:
192.168.0.99:60020, regionname: .META.,,1, startKey: <>} complete
2010-11-01 16:00:59,688 INFO org.apache.hadoop.hbase.master.BaseScanner: All
1 .META. region(s) scanned
2010-11-01 16:01:11,858 INFO org.apache.hadoop.hbase.master.ServerManager: 3
region servers, 0 dead, average load 1.0
2010-11-01 16:01:13,103 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scanning meta region {server: 192.168.0.134:60020,
regionname: -ROOT-,,0, startKey: <>}
2010-11-01 16:01:13,111 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scan of 1 row(s) of meta region {server:
192.168.0.134:60020, regionname: -ROOT-,,0, startKey: <>} complete
2010-11-01 16:01:59,677 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scanning meta region {server: 192.168.0.99:60020,
regionname: .META.,,1, startKey: <>}
2010-11-01 16:01:59,688 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.metaScanner scan of 1 row(s) of meta region {server:
192.168.0.99:60020, regionname: .META.,,1, startKey: <>} complete
2010-11-01 16:01:59,688 INFO org.apache.hadoop.hbase.master.BaseScanner: All
1 .META. region(s) scanned
2010-11-01 16:02:11,859 INFO org.apache.hadoop.hbase.master.ServerManager: 3
region servers, 0 dead, average load 1.0
2010-11-01 16:02:13,104 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scanning meta region {server: 192.168.0.134:60020,
regionname: -ROOT-,,0, startKey: <>}
2010-11-01 16:02:13,117 INFO org.apache.hadoop.hbase.master.BaseScanner:
RegionManager.rootScanner scan of 1 row(s) of meta region {server:
192.168.0.134:60020, regionname: -ROOT-,,0, startKey: <>} complete
2010-11-01 16:02:55,254 INFO org.apache.hadoop.hbase.regionserver.HLog: HLog
configuration: blocksize=67108864, rollsize=63753420, enabled=true,
flushlogentries=100, optionallogflushinternal=10000ms
2010-11-01 16:02:55,262 INFO org.apache.hadoop.hbase.regionserver.HLog: New
hlog /hbase/Webevent/913558333/.logs/hlog.dat.1288607575254
2010-11-01 16:02:55,263 DEBUG org.apache.hadoop.hbase.regionserver.HRegion:
Creating region Webevent,,1288607575222, encoded=913558333
On Thu, Nov 11, 2010 at 10:58 PM, Jean-Daniel Cryans <jdcry...@apache.org>wrote:
> Did you take a look at that region server's log at the time your MR
> job was running? See any obvious exceptions? Was the machine swapping
> at that time?
>
> J-D
>
> On Thu, Nov 11, 2010 at 12:43 AM, Hari Sreekumar
> <hsreeku...@clickable.com> wrote:
> > Hi,
> >
> > I am getting a lot of these RetriesExhaustedExceptions when I run my m/r
> > job. This happens with the 116 server only. What could be the issue? I
> have
> > checked that RS is running on that server, and 192.168.1.116:60030 is
> also
> > working fine..
> >
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Trying to
> contact
> > region server 192.168.1.116:60020 for region
> >
> Webevent,de6c33d0-4e17-47e5-af8a-f88f0af32235_1273198490000_a53c83e4-7a80-418c-bc99-f2f955bda9b2,1289462602425,
> > row
> >
> 'e8f3e3c3-606e-4d1b-a84f-94c5421d153f_1273198296000_23717002-51e3-48e8-9fa4-7618e9728b93',
> > but failed after 10 attempts.
> > Exceptions:
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> > org.apache.hadoop.hbase.client.RetriesExhaustedException: Failed setting
> up
> > proxy to /192.168.1.116:60020 after attempts=1
> >
> > at
> >
> org.apache.hadoop.hbase.client.HConnectionManager$TableServers.getRegionServerWithRetries(HConnectionManager.java:1045)
> > at
> >
> org.apache.hadoop.hbase.client.HConnectionManager$TableServers$3.doCall(HConnectionManager.java:1230)
> > at
> >
> org.apache.hadoop.hbase.client.HConnectionManager$TableServers$Batch.process(HConnectionManager.java:1152)
> > at
> >
> org.apache.hadoop.hbase.client.HConnectionManager$TableServers.processBatchOfRows(HConnectionManager.java:1238)
> > at org.apache.hadoop.hbase.client.HTable.flushCommits(HTable.java:666)
> > at org.apache.hadoop.hbase.client.HTable.put(HTable.java:510)
> > at
> >
> org.apache.hadoop.hbase.mapreduce.TableOutputFormat$TableRecordWriter.write(TableOutputFormat.java:94)
> > at
> >
> org.apache.hadoop.hbase.mapreduce.TableOutputFormat$TableRecordWriter.write(TableOutputFormat.java:55)
> > at
> >
> org.apache.hadoop.mapred.MapTask$NewDirectOutputCollector.write(MapTask.java:498)
> > at
> >
> org.apache.hadoop.mapreduce.TaskInputOutputContext.write(TaskInputOutputContext.java:80)
> > at BulkUpload$BulkUploadMapper.map(Unknown Source)
> > at BulkUpload$BulkUploadMapper.map(Unknown Source)
> > at org.apache.hadoop.mapreduce.Mapper.run(Mapper.java:144)
> > at org.apache.hadoop.mapred.MapTask.runNewMapper(MapTask.java:621)
> > at org.apache.hadoop.mapred.MapTask.run(MapTask.java:305)
> > at org.apache.hadoop.mapred.Child.main(Child.java:170)
> >
> > thanks,
> > hari
> >
>
