did you closed block cache when you used mr dump？ On Mar 12, 2013 1:06 PM, "Lu, Wei" <w...@microstrategy.com> wrote:
> Hi, > > When we use mapreduce to dump data from a pretty large table on hbase. One > region server crash and then another. Mapreduce is deployed together with > hbase. > > 1) From log of the region server, there are both "next" and "multi" > operations on going. Is it because there is write/read conflict that cause > scanner timeout? > 2) Region server has 24 cores, and # max map tasks is 24 too; the table > has about 30 regions (each of size 0.5G) on the region server, is it > because cpu is all used by mapreduce and that case region server slow and > then timeout? > 2) current hbase.regionserver.handler.count is 10 by default, should it be > enlarged? > > Please give us some advices. > > Thanks, > Wei > > > Log information: > > > [Regionserver rs21:] > > 2013-03-11 18:36:28,148 INFO > org.apache.hadoop.hbase.regionserver.wal.HLog: Roll /hbase/.logs/ > adcbg21.machine.wisdom.com,60020,1363010589837/rs21%2C60020%2C1363010589837.1363025554488, > entries=22417, filesize=127539793. for > /hbase/.logs/rs21,60020,1363010589837/rs21%2C60020%2C1363010589837.1363026988052 > 2013-03-11 18:37:39,481 WARN org.apache.hadoop.hbase.util.Sleeper: We > slept 28183ms instead of 3000ms, this is likely due to a long garbage > collecting pause and it's usually bad, see > http://hbase.apache.org/book.html#trouble.rs.runtime.zkexpired > 2013-03-11 18:37:40,163 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":29830,"call":"next(1656517918313948447, 1000), rpc > version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.127.21:56058 > ","starttimems":1363027030280,"queuetimems":4602,"class":"HRegionServer","responsesize":2774484,"method":"next"} > 2013-03-11 18:37:40,163 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":31195,"call":"next(-8353194140406556404, 1000), rpc > version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.127.21:56529 > ","starttimems":1363027028804,"queuetimems":3634,"class":"HRegionServer","responsesize":2270919,"method":"next"} > 2013-03-11 18:37:40,163 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":30965,"call":"next(2623756537510669130, 1000), rpc > version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.127.21:56146 > ","starttimems":1363027028807,"queuetimems":3484,"class":"HRegionServer","responsesize":2753299,"method":"next"} > 2013-03-11 18:37:40,236 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":31023,"call":"next(5293572780165196795, 1000), rpc > version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.127.21:56069 > ","starttimems":1363027029086,"queuetimems":3589,"class":"HRegionServer","responsesize":2722543,"method":"next"} > 2013-03-11 18:37:40,368 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":31160,"call":"next(-4285417329791344278, 1000), rpc > version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.127.21:56586 > ","starttimems":1363027029204,"queuetimems":3707,"class":"HRegionServer","responsesize":2938870,"method":"next"} > 2013-03-11 18:37:43,652 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":31249,"call":"multi(org.apache.hadoop.hbase.client.MultiAction@2d19985a), > rpc version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.109.21:35342 > ","starttimems":1363027031505,"queuetimems":5720,"class":"HRegionServer","responsesize":0,"method":"multi"} > 2013-03-11 18:37:49,108 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":38813,"call":"multi(org.apache.hadoop.hbase.client.MultiAction@19c59a2e), > rpc version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.125.11:57078 > ","starttimems":1363027030273,"queuetimems":4663,"class":"HRegionServer","responsesize":0,"method":"multi"} > 2013-03-11 18:37:50,410 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":38893,"call":"multi(org.apache.hadoop.hbase.client.MultiAction@40022ddb), > rpc version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.109.20:51698 > ","starttimems":1363027031505,"queuetimems":5720,"class":"HRegionServer","responsesize":0,"method":"multi"} > 2013-03-11 18:37:50,642 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":40037,"call":"multi(org.apache.hadoop.hbase.client.MultiAction@6b8bc8cf), > rpc version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.125.11:57078 > ","starttimems":1363027030601,"queuetimems":4818,"class":"HRegionServer","responsesize":0,"method":"multi"} > 2013-03-11 18:37:51,529 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":10880,"call":"multi(org.apache.hadoop.hbase.client.MultiAction@6928d7b), > rpc version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.125.11:57076 > ","starttimems":1363027060645,"queuetimems":34763,"class":"HRegionServer","responsesize":0,"method":"multi"} > 2013-03-11 18:37:51,776 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":41327,"call":"multi(org.apache.hadoop.hbase.client.MultiAction@354baf25), > rpc version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.125.11:57076 > ","starttimems":1363027030411,"queuetimems":4680,"class":"HRegionServer","responsesize":0,"method":"multi"} > 2013-03-11 18:38:32,361 WARN org.apache.hadoop.ipc.HBaseServer: > (responseTooSlow): > {"processingtimems":10204,"call":"multi(org.apache.hadoop.hbase.client.MultiAction@6d86b477), > rpc version=1, client version=29, methodsFingerPrint=54742778","client":" > 10.20.125.10:36950 > ","starttimems":1363027102044,"queuetimems":11027,"class":"HRegionServer","responsesize":0,"method":"multi"} > > ---------------------------------------------------------- > [master:] > 2013-03-11 18:35:39,386 WARN org.apache.hadoop.conf.Configuration: > fs.default.name is deprecated. Instead, use fs.defaultFS > 2013-03-11 18:38:25,892 INFO org.apache.hadoop.hbase.master.LoadBalancer: > Skipping load balancing because balanced cluster; servers=10 regions=477 > average=47.7 mostloaded=52 leastloaded=45 > 2013-03-11 18:39:42,002 INFO > org.apache.hadoop.hbase.zookeeper.RegionServerTracker: RegionServer > ephemeral node deleted, processing expiration [rs21,60020,1363010589837] > 2013-03-11 18:39:42,007 INFO > org.apache.hadoop.hbase.master.handler.ServerShutdownHandler: Splitting > logs for rs21,60020,1363010589837 > 2013-03-11 18:39:42,024 INFO > org.apache.hadoop.hbase.master.SplitLogManager: dead splitlog workers > [rs21,60020,1363010589837] > 2013-03-11 18:39:42,033 INFO > org.apache.hadoop.hbase.master.SplitLogManager: started splitting logs in > [hdfs://rs26/hbase/.logs/rs21,60020,1363010589837-splitting] > 2013-03-11 18:39:42,179 INFO > org.apache.hadoop.hbase.master.SplitLogManager: task > /hbase/splitlog/hdfs%3A%2F%2Frs26%3A8020%2Fhbase%2F.logs%2Frs21%2C60020%2C1363010589837-splitting%2Frs21%252C60020%252C1363010589837.1363010594599 > acquired by rs19,1363010590987 > > > ---------------------------------------------------------- > [Regionserver rs21:] > 2013-03-11 18:40:06,326 WARN org.apache.hadoop.ipc.HBaseServer: IPC Server > Responder, call next(8419833035992682478, 1000), rpc version=1, client > version=29, methodsFingerPrint=54742778 from 10.20.127.21:33592: output > error > 2013-03-11 18:40:06,326 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -1069130278416755239 lease expired > 2013-03-11 18:40:06,327 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -7554305902624086957 lease expired > 2013-03-11 18:40:06,327 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -1817452922125791171 lease expired > 2013-03-11 18:40:06,327 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -7601125239682768076 lease expired > 2013-03-11 18:40:06,327 WARN org.apache.hadoop.hbase.util.Sleeper: We > slept 82186ms instead of 3000ms, this is likely due to a long garbage > collecting pause and it's usually bad, see > http://hbase.apache.org/book.html#trouble.rs.runtime.zkexpired > 2013-03-11 18:40:06,327 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 5506385887933665130 lease expired > 2013-03-11 18:40:06,327 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 8405483593065293761 lease expired > 2013-03-11 18:40:06,327 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 8270919548717867130 lease expired > 2013-03-11 18:40:06,327 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -5350053253744349360 lease expired > 2013-03-11 18:40:06,328 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -2774223416111392810 lease expired > 2013-03-11 18:40:06,328 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 5293572780165196795 lease expired > 2013-03-11 18:40:06,328 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 2904518513855545553 lease expired > 2013-03-11 18:40:06,328 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 121972859714825295 lease expired > 2013-03-11 18:40:06,328 WARN org.apache.hadoop.ipc.HBaseServer: IPC Server > Responder, call next(1316499555392112856, 1000), rpc version=1, client > version=29, methodsFingerPrint=54742778 from 10.20.127.21:33552: output > error > 2013-03-11 18:40:06,328 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 751003440851341708 lease expired > 2013-03-11 18:40:06,328 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 3456313588148401866 lease expired > 2013-03-11 18:40:06,328 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -1893617732870830965 lease expired > 2013-03-11 18:40:06,329 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -677968643251998870 lease expired > 2013-03-11 18:40:06,329 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 2623756537510669130 lease expired > 2013-03-11 18:40:06,329 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > -4453586756904422814 lease expired > 2013-03-11 18:40:06,329 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 4513044921501336208 lease expired > 2013-03-11 18:40:06,329 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 8419833035992682478 lease expired > 2013-03-11 18:40:06,329 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 6790476379016482048 lease expired > 2013-03-11 18:40:06,329 INFO > org.apache.hadoop.hbase.regionserver.HRegionServer: Scanner > 1316499555392112856 lease expired > 2013-03-11 18:40:06,337 WARN org.apache.hadoop.ipc.HBaseServer: IPC Server > Responder, call next(6790476379016482048, 1000), rpc version=1, client > version=29, methodsFingerPrint=54742778 from 10.20.127.21:33603: output > error > 2013-03-11 18:40:06,485 INFO org.apache.zookeeper.ClientCnxn: Client > session timed out, have not heard from server in 86375ms for sessionid > 0x13c9789d2289cd1, closing socket connection and attempting reconnect > 2013-03-11 18:40:06,493 WARN org.apache.hadoop.ipc.HBaseServer: IPC Server > handler 5 on 60020 caught: java.nio.channels.ClosedChannelException > at > sun.nio.ch.SocketChannelImpl.ensureWriteOpen(SocketChannelImpl.java:133) > at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:324) > at > org.apache.hadoop.hbase.ipc.HBaseServer.channelIO(HBaseServer.java:1732) > at > org.apache.hadoop.hbase.ipc.HBaseServer.channelWrite(HBaseServer.java:1675) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Responder.processResponse(HBaseServer.java:940) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Responder.doRespond(HBaseServer.java:1019) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Call.sendResponseIfReady(HBaseServer.java:425) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:1365) > > 2013-03-11 18:40:06,489 INFO org.apache.hadoop.ipc.HBaseServer: IPC Server > Responder: doAsyncWrite threw exception java.io.IOException: Broken pipe > 2013-03-11 18:40:06,517 WARN org.apache.hadoop.ipc.HBaseServer: IPC Server > handler 8 on 60020 caught: java.io.IOException: Broken pipe > at sun.nio.ch.FileDispatcher.write0(Native Method) > at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29) > at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:69) > at sun.nio.ch.IOUtil.write(IOUtil.java:40) > at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334) > at > org.apache.hadoop.hbase.ipc.HBaseServer.channelIO(HBaseServer.java:1732) > at > org.apache.hadoop.hbase.ipc.HBaseServer.channelWrite(HBaseServer.java:1675) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Responder.processResponse(HBaseServer.java:940) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Responder.doRespond(HBaseServer.java:1019) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Call.sendResponseIfReady(HBaseServer.java:425) > at > org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:1365) > > > >
