hbase-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Ankit Jain <ankitjainc...@gmail.com>
Subject Re: HBase Multiget taking more time
Date Tue, 25 Jun 2013 16:25:35 GMT
Hi Jean-Marc,

Below is the GC logs:

2013-06-25T17:54:03.408+0530: 81287.747: [GC [PSYoungGen:
107898K->11598K(112896K)] 917679K->842276K(1117696K), 0.0055720 secs]
[Times: user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:54:09.152+0530: 81293.491: [GC [PSYoungGen:
94337K->1381K(83008K)] 925015K->848523K(1087808K), 0.0046760 secs] [Times:
user=0.03 sys=0.01, real=0.00 secs]
2013-06-25T17:54:11.803+0530: 81296.142: [GC [PSYoungGen:
82929K->16820K(111552K)] 930071K->864126K(1116352K), 0.0046660 secs]
[Times: user=0.02 sys=0.01, real=0.00 secs]
2013-06-25T17:54:14.055+0530: 81298.394: [GC [PSYoungGen:
97249K->23006K(112064K)] 944555K->888959K(1116864K), 0.0060900 secs]
[Times: user=0.05 sys=0.00, real=0.01 secs]
2013-06-25T17:54:17.851+0530: 81302.190: [GC [PSYoungGen:
101799K->4420K(110784K)] 967752K->890974K(1115584K), 0.0061190 secs]
[Times: user=0.04 sys=0.00, real=0.00 secs]
2013-06-25T17:54:21.291+0530: 81305.630: [GC [PSYoungGen:
82640K->21774K(111296K)] 969194K->908649K(1116096K), 0.0037320 secs]
[Times: user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:54:23.920+0530: 81308.259: [GC [PSYoungGen:
98984K->23970K(110848K)] 985859K->923253K(1115648K), 0.0132180 secs]
[Times: user=0.07 sys=0.00, real=0.02 secs]
2013-06-25T17:54:25.903+0530: 81310.242: [GC [PSYoungGen:
99601K->17204K(91904K)] 998884K->935208K(1096704K), 0.0082780 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:54:28.685+0530: 81313.024: [GC [PSYoungGen:
91832K->14296K(104512K)] 1009836K->963108K(1109312K), 0.0063080 secs]
[Times: user=0.05 sys=0.00, real=0.00 secs]
2013-06-25T17:54:32.422+0530: 81316.761: [GC [PSYoungGen:
88012K->17955K(90752K)] 1036825K->981292K(1095552K), 0.0061840 secs]
[Times: user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:54:35.643+0530: 81319.982: [GC [PSYoungGen:
90685K->5938K(103424K)] 1054022K->985778K(1108224K), 0.0047240 secs]
[Times: user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:54:35.648+0530: 81319.987: [Full GC [PSYoungGen:
5938K->0K(103424K)] [ParOldGen: 979840K->780760K(1011392K)]
985778K->780760K(1114816K) [PSPermGen: 26558K->26558K(26816K)], 0.1978330
secs] [Times: user=0.90 sys=0.03, real=0.20 secs]
2013-06-25T17:54:38.959+0530: 81323.298: [GC [PSYoungGen:
71862K->3092K(74112K)] 852623K->783853K(1085504K), 0.0020070 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:54:42.335+0530: 81326.674: [GC [PSYoungGen:
74060K->2528K(101696K)] 854821K->783289K(1113088K), 0.0022380 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:54:45.300+0530: 81329.639: [GC [PSYoungGen:
72589K->6572K(75776K)] 853350K->787333K(1087168K), 0.0035930 secs] [Times:
user=0.01 sys=0.00, real=0.00 secs]
2013-06-25T17:54:47.950+0530: 81332.289: [GC [PSYoungGen:
75518K->16388K(98432K)] 856279K->797149K(1109824K), 0.0052960 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:54:50.034+0530: 81334.374: [GC [PSYoungGen:
84647K->31290K(98752K)] 865408K->822643K(1110144K), 0.0062490 secs] [Times:
user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:54:52.452+0530: 81336.791: [GC [PSYoungGen:
98695K->40435K(96384K)] 890048K->844556K(1107776K), 0.0096790 secs] [Times:
user=0.05 sys=0.01, real=0.01 secs]
2013-06-25T17:54:54.979+0530: 81339.318: [GC [PSYoungGen:
96368K->27980K(83392K)] 900489K->840470K(1094784K), 0.0061010 secs] [Times:
user=0.05 sys=0.00, real=0.00 secs]
2013-06-25T17:54:57.448+0530: 81341.787: [GC [PSYoungGen:
83285K->27164K(105344K)] 895774K->839653K(1116736K), 0.0055360 secs]
[Times: user=0.04 sys=0.00, real=0.00 secs]
2013-06-25T17:55:00.187+0530: 81344.526: [GC [PSYoungGen:
81227K->20893K(75136K)] 893716K->833382K(1086528K), 0.0072650 secs] [Times:
user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:55:02.649+0530: 81346.988: [GC [PSYoungGen:
75054K->33701K(100672K)] 887543K->846190K(1112064K), 0.0052480 secs]
[Times: user=0.04 sys=0.00, real=0.00 secs]
2013-06-25T17:55:04.561+0530: 81348.900: [GC [PSYoungGen:
85784K->47890K(102272K)] 898273K->860380K(1113664K), 0.0074260 secs]
[Times: user=0.05 sys=0.00, real=0.01 secs]
2013-06-25T17:55:06.052+0530: 81350.391: [GC [PSYoungGen:
99402K->31269K(106432K)] 911892K->862255K(1117824K), 0.0073410 secs]
[Times: user=0.05 sys=0.00, real=0.01 secs]
2013-06-25T17:55:07.678+0530: 81352.017: [GC [PSYoungGen:
80828K->19721K(70336K)] 911813K->867359K(1081728K), 0.0069240 secs] [Times:
user=0.05 sys=0.00, real=0.01 secs]
2013-06-25T17:55:09.710+0530: 81354.050: [GC [PSYoungGen:
70202K->19123K(99840K)] 917840K->867139K(1111232K), 0.0056040 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:55:11.601+0530: 81355.940: [GC [PSYoungGen:
69114K->33268K(101952K)] 917130K->895918K(1113344K), 0.0112820 secs]
[Times: user=0.07 sys=0.00, real=0.01 secs]
2013-06-25T17:55:13.459+0530: 81357.798: [GC [PSYoungGen:
82859K->28418K(98560K)] 945509K->893292K(1109952K), 0.0062720 secs] [Times:
user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:55:15.101+0530: 81359.441: [GC [PSYoungGen:
77529K->18880K(67648K)] 942403K->906685K(1079040K), 0.0063850 secs] [Times:
user=0.04 sys=0.00, real=0.00 secs]
2013-06-25T17:55:16.790+0530: 81361.129: [GC [PSYoungGen:
67560K->6028K(97984K)] 955365K->902468K(1109376K), 0.0045320 secs] [Times:
user=0.03 sys=0.01, real=0.00 secs]
2013-06-25T17:55:18.897+0530: 81363.236: [GC [PSYoungGen:
54080K->3622K(98176K)] 950521K->904395K(1109568K), 0.0027770 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:55:20.995+0530: 81365.334: [GC [PSYoungGen:
51181K->3747K(97536K)] 951954K->904886K(1108928K), 0.0025150 secs] [Times:
user=0.02 sys=0.01, real=0.00 secs]
2013-06-25T17:55:23.146+0530: 81367.485: [GC [PSYoungGen:
50915K->2769K(49536K)] 952054K->903908K(1060928K), 0.0037100 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:25.189+0530: 81369.528: [GC [PSYoungGen:
49486K->5846K(93952K)] 950625K->906985K(1105344K), 0.0046930 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:55:26.633+0530: 81370.973: [GC [PSYoungGen:
52134K->11310K(57280K)] 953274K->912449K(1068672K), 0.0067390 secs] [Times:
user=0.04 sys=0.00, real=0.00 secs]
2013-06-25T17:55:27.955+0530: 81372.294: [GC [PSYoungGen:
57258K->25888K(92224K)] 958397K->927092K(1103616K), 0.0052790 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:29.633+0530: 81373.972: [GC [PSYoungGen:
71209K->26957K(92416K)] 972412K->928248K(1103808K), 0.0046870 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:31.056+0530: 81375.395: [GC [PSYoungGen:
71901K->38258K(88576K)] 973193K->939634K(1099968K), 0.0095330 secs] [Times:
user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:55:32.145+0530: 81376.484: [GC [PSYoungGen:
78679K->16912K(57088K)] 980055K->936917K(1068480K), 0.0066480 secs] [Times:
user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:55:33.290+0530: 81377.629: [GC [PSYoungGen:
57003K->17509K(84032K)] 977008K->948329K(1095424K), 0.0061570 secs] [Times:
user=0.04 sys=0.00, real=0.01 secs]
2013-06-25T17:55:34.814+0530: 81379.153: [GC [PSYoungGen:
57270K->5253K(44800K)] 988090K->942406K(1056192K), 0.0052420 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:55:36.117+0530: 81380.456: [GC [PSYoungGen:
44705K->10622K(82496K)] 981858K->950092K(1093888K), 0.0038100 secs] [Times:
user=0.03 sys=0.01, real=0.00 secs]
2013-06-25T17:55:37.293+0530: 81381.632: [GC [PSYoungGen:
49724K->8277K(47168K)] 989194K->954301K(1058560K), 0.0037470 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:55:38.448+0530: 81382.787: [GC [PSYoungGen:
47080K->14036K(77568K)] 993169K->962479K(1088960K), 0.0049070 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:55:39.823+0530: 81384.162: [GC [PSYoungGen:
51688K->5853K(44224K)] 1000131K->966748K(1055616K), 0.0050250 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:55:41.238+0530: 81385.577: [GC [PSYoungGen:
44189K->7572K(76032K)] 1005084K->968624K(1087424K), 0.0030080 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:43.238+0530: 81387.577: [GC [PSYoungGen:
45612K->1633K(39488K)] 1006664K->966869K(1050880K), 0.0087630 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:55:44.628+0530: 81388.967: [GC [PSYoungGen:
39447K->9662K(72384K)] 1004683K->975068K(1083776K), 0.0045410 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:46.310+0530: 81390.649: [GC [PSYoungGen:
47203K->3346K(40704K)] 1012609K->975623K(1052096K), 0.0045720 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:47.617+0530: 81391.956: [GC [PSYoungGen:
40602K->6016K(70144K)] 1012879K->978626K(1081536K), 0.0032490 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:48.773+0530: 81393.112: [GC [PSYoungGen:
43015K->11389K(48192K)] 1015626K->984355K(1059584K), 0.0036910 secs]
[Times: user=0.03 sys=0.01, real=0.01 secs]
2013-06-25T17:55:49.701+0530: 81394.040: [GC [PSYoungGen:
48189K->12247K(65344K)] 1021155K->991931K(1076736K), 0.0086960 secs]
[Times: user=0.05 sys=0.00, real=0.01 secs]
2013-06-25T17:55:51.250+0530: 81395.589: [GC [PSYoungGen:
48760K->3440K(39744K)] 1028444K->995513K(1051136K), 0.0041160 secs] [Times:
user=0.02 sys=0.01, real=0.00 secs]
2013-06-25T17:55:52.490+0530: 81396.830: [GC [PSYoungGen:
39675K->12689K(63552K)] 1031747K->1005189K(1074944K), 0.0037870 secs]
[Times: user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:55:54.135+0530: 81398.474: [GC [PSYoungGen:
48679K->2953K(38784K)] 1041179K->1001838K(1050176K), 0.0039330 secs]
[Times: user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:55:54.139+0530: 81398.478: [Full GC [PSYoungGen:
2953K->0K(38784K)] [ParOldGen: 998885K->740515K(947072K)]
1001838K->740515K(985856K) [PSPermGen: 26558K->26558K(26816K)], 0.1832320
secs] [Times: user=0.97 sys=0.04, real=0.18 secs]
2013-06-25T17:55:55.901+0530: 81400.240: [GC [PSYoungGen:
35759K->3531K(62272K)] 776274K->744047K(1009344K), 0.0027690 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:55:57.805+0530: 81402.144: [GC [PSYoungGen:
39019K->1973K(37248K)] 779534K->743173K(984320K), 0.0027090 secs] [Times:
user=0.01 sys=0.00, real=0.01 secs]
2013-06-25T17:55:59.245+0530: 81403.584: [GC [PSYoungGen:
37224K->5929K(59136K)] 778423K->747824K(1006208K), 0.0025460 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:00.283+0530: 81404.622: [GC [PSYoungGen:
40859K->17694K(60544K)] 782755K->759589K(1007616K), 0.0040440 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:01.162+0530: 81405.501: [GC [PSYoungGen:
50711K->26650K(57664K)] 792606K->770839K(1004736K), 0.0082020 secs] [Times:
user=0.03 sys=0.01, real=0.01 secs]
2013-06-25T17:56:01.843+0530: 81406.182: [GC [PSYoungGen:
56780K->11804K(41792K)] 800968K->767178K(988864K), 0.0054550 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:02.663+0530: 81407.002: [GC [PSYoungGen:
41678K->10773K(56128K)] 797052K->780183K(1003200K), 0.0043570 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:03.442+0530: 81407.781: [GC [PSYoungGen:
40461K->11516K(41088K)] 809870K->790192K(988160K), 0.0046650 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:04.165+0530: 81408.504: [GC [PSYoungGen:
41040K->9097K(53632K)] 819716K->792103K(1000704K), 0.0037540 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:05.323+0530: 81409.662: [GC [PSYoungGen:
38453K->9934K(39168K)] 821459K->793769K(986240K), 0.0035020 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:06.705+0530: 81411.044: [GC [PSYoungGen:
39084K->2568K(53248K)] 822919K->792950K(1000320K), 0.0025540 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:07.848+0530: 81412.187: [GC [PSYoungGen:
31509K->3054K(31872K)] 821891K->793769K(978944K), 0.0024270 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:09.425+0530: 81413.764: [GC [PSYoungGen:
31785K->2907K(51904K)] 822501K->793847K(998976K), 0.0030470 secs] [Times:
user=0.02 sys=0.01, real=0.01 secs]
2013-06-25T17:56:10.779+0530: 81415.118: [GC [PSYoungGen:
31573K->2515K(31104K)] 822514K->793596K(978176K), 0.0040010 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:11.581+0530: 81415.921: [GC [PSYoungGen:
29418K->8513K(48960K)] 822547K->801705K(996032K), 0.0036200 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:12.432+0530: 81416.771: [GC [PSYoungGen:
36928K->11120K(39424K)] 830121K->809346K(986496K), 0.0045010 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:13.191+0530: 81417.530: [GC [PSYoungGen:
39403K->4775K(48256K)] 837628K->809217K(995328K), 0.0030740 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:13.989+0530: 81418.328: [GC [PSYoungGen:
32911K->5219K(33280K)] 837353K->810814K(980352K), 0.0037300 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:14.731+0530: 81419.070: [GC [PSYoungGen:
33212K->8148K(45760K)] 838807K->814322K(992832K), 0.0031530 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:15.373+0530: 81419.713: [GC [PSYoungGen:
36037K->8412K(36224K)] 842212K->816981K(983296K), 0.0031700 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:16.263+0530: 81420.602: [GC [PSYoungGen:
36188K->16101K(43968K)] 844757K->827893K(991040K), 0.0036670 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:17.358+0530: 81421.697: [GC [PSYoungGen:
41829K->2498K(28224K)] 853622K->826736K(975296K), 0.0034870 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:18.489+0530: 81422.828: [GC [PSYoungGen:
28155K->3323K(44736K)] 852392K->827933K(991808K), 0.0035680 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:19.697+0530: 81424.036: [GC [PSYoungGen:
28810K->3025K(28480K)] 853421K->828207K(975552K), 0.0030400 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:20.927+0530: 81425.266: [GC [PSYoungGen:
28387K->2812K(43392K)] 853569K->828150K(990464K), 0.0023810 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:22.025+0530: 81426.364: [GC [PSYoungGen:
28029K->4422K(29632K)] 853367K->830368K(976704K), 0.0029670 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:23.233+0530: 81427.572: [GC [PSYoungGen:
29565K->3037K(41728K)] 855512K->829565K(988800K), 0.0032560 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:24.312+0530: 81428.651: [GC [PSYoungGen:
28033K->2891K(27840K)] 854561K->829652K(974912K), 0.0023130 secs] [Times:
user=0.02 sys=0.01, real=0.00 secs]
2013-06-25T17:56:25.019+0530: 81429.358: [GC [PSYoungGen:
27764K->7494K(40576K)] 854525K->834380K(987648K), 0.0029400 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:26.289+0530: 81430.628: [GC [PSYoungGen:
32200K->3852K(28544K)] 859085K->830857K(975616K), 0.0026480 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:27.438+0530: 81431.777: [GC [PSYoungGen:
28470K->4962K(38400K)] 855475K->836164K(985472K), 0.0039110 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:28.423+0530: 81432.762: [GC [PSYoungGen:
29407K->5839K(30272K)] 860694K->837906K(977344K), 0.0031360 secs] [Times:
user=0.02 sys=0.01, real=0.00 secs]
2013-06-25T17:56:29.553+0530: 81433.892: [GC [PSYoungGen:
30169K->2879K(37440K)] 862236K->847557K(984512K), 0.0033760 secs] [Times:
user=0.02 sys=0.01, real=0.00 secs]
2013-06-25T17:56:30.432+0530: 81434.771: [GC [PSYoungGen:
27097K->5825K(30016K)] 871775K->850925K(977088K), 0.0028820 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:31.318+0530: 81435.657: [GC [PSYoungGen:
29911K->5174K(35456K)] 875011K->850600K(982528K), 0.0042970 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:31.969+0530: 81436.308: [GC [PSYoungGen:
29172K->12118K(36160K)] 874662K->864099K(983232K), 0.0059780 secs] [Times:
user=0.05 sys=0.00, real=0.01 secs]
2013-06-25T17:56:32.615+0530: 81436.954: [GC [PSYoungGen:
35939K->6728K(36544K)] 887984K->877639K(983616K), 0.0045630 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:33.784+0530: 81438.123: [GC [PSYoungGen:
30432K->5489K(29120K)] 901407K->876874K(976192K), 0.0046990 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:34.174+0530: 81438.513: [GC [PSYoungGen:
29069K->12825K(34624K)] 900454K->886597K(981696K), 0.0044030 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:35.095+0530: 81439.434: [GC [PSYoungGen:
34542K->8609K(30336K)] 908313K->890296K(977408K), 0.0043200 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:35.810+0530: 81440.149: [GC [PSYoungGen:
30290K->7428K(35008K)] 911976K->899443K(982080K), 0.0046810 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:36.615+0530: 81440.954: [GC [PSYoungGen:
28999K->7185K(28800K)] 921014K->901405K(975872K), 0.0049030 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:37.570+0530: 81441.909: [GC [PSYoungGen:
28716K->3187K(34816K)] 922936K->903293K(981888K), 0.0033000 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:38.604+0530: 81442.943: [GC [PSYoungGen:
24662K->2949K(24448K)] 924767K->903427K(971520K), 0.0028190 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:39.490+0530: 81443.829: [GC [PSYoungGen:
24351K->2952K(34304K)] 924830K->904022K(981376K), 0.0025140 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:40.490+0530: 81444.829: [GC [PSYoungGen:
24303K->2338K(23680K)] 925373K->903718K(970752K), 0.0025190 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:41.410+0530: 81445.749: [GC [PSYoungGen:
23640K->4454K(33152K)] 925020K->905897K(980224K), 0.0046150 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:42.385+0530: 81446.724: [GC [PSYoungGen:
25648K->3630K(24832K)] 927092K->905899K(971904K), 0.0049550 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:43.253+0530: 81447.593: [GC [PSYoungGen:
24799K->3868K(32320K)] 927068K->906285K(979392K), 0.0041770 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:43.860+0530: 81448.199: [GC [PSYoungGen:
24954K->6420K(27520K)] 927371K->909248K(974592K), 0.0037940 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:44.497+0530: 81448.836: [GC [PSYoungGen:
25697K->6881K(32448K)] 928525K->913056K(979520K), 0.0045160 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:45.192+0530: 81449.531: [GC [PSYoungGen:
27819K->6283K(27264K)] 933995K->918912K(974336K), 0.0043050 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:45.648+0530: 81449.987: [GC [PSYoungGen:
27203K->8451K(31616K)] 939832K->925542K(978688K), 0.0045800 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:46.238+0530: 81450.577: [GC [PSYoungGen:
28792K->7835K(32128K)] 945883K->930233K(979200K), 0.0073840 secs] [Times:
user=0.05 sys=0.00, real=0.01 secs]
2013-06-25T17:56:46.706+0530: 81451.045: [GC [PSYoungGen:
28228K->6682K(35008K)] 950626K->933755K(982080K), 0.0068550 secs] [Times:
user=0.05 sys=0.00, real=0.00 secs]
2013-06-25T17:56:47.452+0530: 81451.791: [GC [PSYoungGen:
30294K->6733K(35200K)] 957367K->936310K(982272K), 0.0031440 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:56:47.984+0530: 81452.323: [GC [PSYoungGen:
30300K->8240K(38208K)] 959877K->942118K(985280K), 0.2906910 secs] [Times:
user=0.00 sys=0.98, real=0.29 secs]
2013-06-25T17:56:49.158+0530: 81453.497: [GC [PSYoungGen:
35181K->10232K(38464K)] 969059K->947745K(985536K), 0.0045960 secs] [Times:
user=0.00 sys=0.03, real=0.00 secs]
2013-06-25T17:56:49.163+0530: 81453.502: [Full GC [PSYoungGen:
10232K->0K(38464K)] [ParOldGen: 937512K->712630K(912832K)]
947745K->712630K(951296K) [PSPermGen: 26558K->26558K(26816K)], 0.1714850
secs] [Times: user=0.49 sys=0.42, real=0.18 secs]
2013-06-25T17:56:50.235+0530: 81454.574: [GC [PSYoungGen:
26892K->12406K(43072K)] 739523K->727859K(955904K), 0.0047400 secs] [Times:
user=0.03 sys=0.01, real=0.00 secs]
2013-06-25T17:56:51.220+0530: 81455.559: [GC [PSYoungGen:
43054K->7364K(44608K)] 758506K->729033K(957440K), 0.0030530 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:52.182+0530: 81456.521: [GC [PSYoungGen:
37979K->4630K(49280K)] 759648K->728520K(962112K), 0.0039480 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:53.050+0530: 81457.389: [GC [PSYoungGen:
40970K->9578K(49536K)] 764859K->735789K(962368K), 0.0037920 secs] [Times:
user=0.03 sys=0.00, real=0.00 secs]
2013-06-25T17:56:54.044+0530: 81458.383: [GC [PSYoungGen:
45930K->12161K(55232K)] 772141K->753204K(968064K), 0.0050660 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:55.097+0530: 81459.436: [GC [PSYoungGen:
53858K->14443K(56448K)] 794965K->768072K(969280K), 0.0044040 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:56.589+0530: 81460.928: [GC [PSYoungGen:
56108K->5528K(64320K)] 809738K->763564K(977152K), 0.0037010 secs] [Times:
user=0.02 sys=0.00, real=0.00 secs]
2013-06-25T17:56:57.763+0530: 81462.102: [GC [PSYoungGen:
53540K->8200K(64832K)] 811575K->766307K(977664K), 0.0066220 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:56:59.543+0530: 81463.882: [GC [PSYoungGen:
56815K->9096K(70656K)] 814922K->769814K(983488K), 0.0081410 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:57:01.924+0530: 81466.263: [GC [PSYoungGen:
65282K->6745K(71232K)] 826000K->773818K(984064K), 0.0054910 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:57:06.603+0530: 81470.942: [GC [PSYoungGen:
62901K->7778K(63360K)] 829973K->777330K(976192K), 0.0037090 secs] [Times:
user=0.02 sys=0.01, real=0.00 secs]
2013-06-25T17:57:08.373+0530: 81472.712: [GC [PSYoungGen:
63257K->3609K(58624K)] 833554K->778315K(971456K), 0.0141470 secs] [Times:
user=0.05 sys=0.00, real=0.02 secs]
2013-06-25T17:57:10.433+0530: 81474.772: [GC [PSYoungGen:
58518K->14068K(67328K)] 833224K->789072K(980160K), 0.0048410 secs] [Times:
user=0.03 sys=0.01, real=0.01 secs]
2013-06-25T17:57:12.776+0530: 81477.115: [GC [PSYoungGen:
67225K->3545K(56192K)] 842229K->788868K(969024K), 0.0029940 secs] [Times:
user=0.02 sys=0.00, real=0.01 secs]
2013-06-25T17:57:14.966+0530: 81479.305: [GC [PSYoungGen:
56135K->5243K(66752K)] 841458K->791207K(979584K), 0.0038740 secs] [Times:
user=0.03 sys=0.00, real=0.01 secs]
2013-06-25T17:57:16.447+0530: 81480.786: [GC [PSYoungGen:
57062K->13659K(67136K)] 843026K->801836K(979968K), 0.0084320 secs]

Thanks,
Ankit Jain


On Tue, Jun 25, 2013 at 7:06 PM, Jean-Marc Spaggiari <
jean-marc@spaggiari.org> wrote:

> Hi Ankit,
>
> Attachements are not working well with the mailing list. Can you post
> them on pastbin? Also, can you please post the servers (region and
> master) logs?
>
> thanks,
>
> JM
>
> 2013/6/25 Ankit Jain <ankitjaincs06@gmail.com>:
> > Hi Jean-Marc/Michael,
> >
> > Thanks for the reply.
> >
> > Hardware detail:
> > Processor: 8 core
> > RAM: 16 GB.
> >
> > We have allotted 4GB of RAM to HBase and also we are ingesting data into
> > HBase in parallel with rate of 50 records(Each record has 20 KB) per sec.
> >
> > Please find the attached GC log.
> >
> > Thanks,
> > Ankit
> >
> >
> > On Tue, Jun 25, 2013 at 6:03 PM, Ankit Jain <ankitjaincs06@gmail.com>
> wrote:
> >>
> >> Hi Jean-Marc/Michael,
> >>
> >> Thanks for the reply.
> >>
> >> Hardware detail:
> >> Processor: 8 core
> >> RAM: 16 GB.
> >>
> >> We have allotted 4GB of RAM to HBase and also we are ingesting data into
> >> HBase in parallel with rate of 50 records(Each record has 20 KB) per
> sec.
> >>
> >> Please find the attached GC log.
> >>
> >> Thanks,
> >> Ankit
> >>
> >>
> >>
> >>
> >>
> >>
> >> On Tue, Jun 25, 2013 at 5:43 PM, Michael Segel <
> michael_segel@hotmail.com>
> >> wrote:
> >>>
> >>> Double your timeout from 60K to 120K.
> >>> While I don't think its the problem... its just a good idea.
> >>>
> >>> What happens if you drop the 50 down to 25?  Do you still fail?
> >>> If not, go to 35,  etc... until you hit a point where you fail.
> >>>
> >>> As Jean-Marc said, we need a bit more information. Including some
> >>> hardware info too.
> >>>
> >>> Thx
> >>>
> >>> -Mike
> >>>
> >>> On Jun 25, 2013, at 4:03 AM, Ankit Jain <ankitjaincs06@gmail.com>
> wrote:
> >>>
> >>> > Hi All,
> >>> >
> >>> > HBase multiget call taking large time and throwing time out
> exception.
> >>> > I am
> >>> > retrieving only 50 records in one call. The size of each record is
20
> >>> > KB.
> >>> >
> >>> > java.net.SocketTimeoutException: 60000 millis timeout while waiting
> for
> >>> > channel to be ready for read. ch :
> >>> > java.nio.channels.SocketChannel[connected
> >>> > local=/192.168.50.122:48695remote=ct-0096/
> >>> > 192.168.50.177:60020]
> >>> >
> >>> > hTable = new HTable(conf, tableName);
> >>> > results = hTable.get(rows);
> >>> >
> >>> > Cluster Detail:
> >>> > 1 master, 1 regionserver and 8 regions
> >>> >
> >>> > --
> >>> > Thanks,
> >>> > Ankit Jain
> >>>
> >>
> >>
> >>
> >> --
> >> Thanks,
> >> Ankit Jain
> >
> >
> >
> >
> > --
> > Thanks,
> > Ankit Jain
>



-- 
Thanks,
Ankit Jain

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message