本帖最后由 ighack 于 2019-6-5 09:31 编辑
[mw_shl_code=bash,true]2019-06-04 18:34:20,376 INFO [moniser,16020,1559617721856_ChoreService_1] regionserver.HRegionServer: moniser,16020,1559617721856-MemstoreFlusherChore requesting flush of Trace
V2,&\x00\x00\x00\x00\x00\x00\x00,1559114757691.8ba4163358f97beb059bbe066b15c6c5. because S has an old edit so flush to free WALs after random delay 194067ms2019-06-04 18:34:20,377 INFO [moniser,16020,1559617721856_ChoreService_1] regionserver.HRegionServer: moniser,16020,1559617721856-MemstoreFlusherChore requesting flush of Trace
V2,\xCC\x00\x00\x00\x00\x00\x00\x00,1559114757691.2e55b6f50195981b03373a114c155b4e. because S has an old edit so flush to free WALs after random delay 179269ms2019-06-04 18:34:20,377 INFO [moniser,16020,1559617721856_ChoreService_1] regionserver.HRegionServer: moniser,16020,1559617721856-MemstoreFlusherChore requesting flush of Appli
cationMapStatisticsSelf_Ver2,\x05\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00,1559114765629.233d41a8e943fd1e4ddb29b694b13dd0. because C has an old edit so flush to free WALs after random delay 242343ms
2019-06-04 18:34:20,828 INFO [regionserver/moniser/192.168.0.238:16020-shortCompactions-1559617738938] regionserver.HStore: Completed compaction of 3 (all) file(s) in S of TraceV2,\xFF\x00\x00\x00\x00\x00\x00\x00,1559114757691.aca60625a1273b36a87e5affac2fd09a. into b1c00fc5a642412db370dbef1d5acbaf(size=82.6 M), total size for store is 82.6 M. This selection was in queue for 0sec, and took 1sec to execute.
2019-06-04 18:34:20,828 INFO [regionserver/moniser/192.168.0.238:16020-shortCompactions-1559617738938] regionserver.CompactSplitThread: Completed compaction: Request = regionName=TraceV2,\xFF\x00\x00\x00\x00\x00\x00\x00,1559114757691.aca60625a1273b36a87e5affac2fd09a., storeName=S, fileCount=3, fileSize=82.7 M (81.0 M, 877.4 K, 819.6 K), priority=7, time=7788170363172924; duration=1sec
2019-06-04 18:34:29,089 INFO [main] zookeeper.ZooKeeper: Client environment:zookeeper.version=3.4.6-1569965, built on 02/20/2014 09:09 GMT
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:host.name=moniser
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:java.version=1.8.0_131
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:java.vendor=Oracle Corporation
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:java.home=/usr/java/jdk1.8.0_131/jre
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:java.class.path=/pinpoint/app/pinpoint/hbase-1.3.1/bin/../conf:/usr/java/jdk1.8.0_131/lib/tools.jar:
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:java.io.tmpdir=/tmp
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:java.compiler=<NA>
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:os.name=Linux
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:os.arch=amd64
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:os.version=3.10.0-862.14.4.el7.x86_64
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:user.name=pinpoint
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:user.home=/pinpoint/app
2019-06-04 18:34:29,090 INFO [main] zookeeper.ZooKeeper: Client environment:user.dir=/pinpoint/app/pinpoint/hbase-1.3.1
2019-06-04 18:34:29,092 INFO [main] zookeeper.ZooKeeper: Initiating client connection, connectString=moniser:2181,basappser2:2181 sessionTimeout=30000 watcher=org.apache.zookeeper.ZooKeeperMain$MyWatcher@3a03464
2019-06-04 18:34:29,113 INFO [main-SendThread(moniser:2181)] zookeeper.ClientCnxn: Opening socket connection to server moniser/192.168.0.238:2181. Will not attempt to authenticate using SASL (unknown error)
2019-06-04 18:34:29,118 INFO [main-SendThread(moniser:2181)] zookeeper.ClientCnxn: Socket connection established to moniser/192.168.0.238:2181,initiating session
2019-06-04 18:34:29,126 INFO [main-SendThread(moniser:2181)] zookeeper.ClientCnxn: Session establishment complete on server moniser/192.168.0.238:2181, sessionid =0x16b01d9f02d003c, negotiated timeout = 80000[/mw_shl_code]
最近又发现这样的日志。
gc很正常啊
[mw_shl_code=bash,true]2019-06-04T18:33:02.284+0800: 26661.606: [GC (Allocation Failure) 2019-06-04T18:33:02.284+0800: 26661.607: [ParNew: 433013K->18499K(463872K), 0.0063271 secs] 1145281K->730766K(1554228K), 0.0064589 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
2019-06-04T18:33:09.949+0800: 26669.272: [GC (Allocation Failure) 2019-06-04T18:33:09.949+0800: 26669.272: [ParNew: 430799K->40491K(463872K), 0.0064531 secs] 1143066K->755126K(1554228K), 0.0066203 secs] [Times: user=0.05 sys=0.00, real=0.01 secs]
2019-06-04T18:33:13.576+0800: 26672.899: [GC (Allocation Failure) 2019-06-04T18:33:13.576+0800: 26672.899: [ParNew: 452843K->3221K(463872K), 0.0142780 secs] 1167478K->737788K(1554228K), 0.0144429 secs] [Times: user=0.06 sys=0.00, real=0.02 secs]
2019-06-04T18:33:24.612+0800: 26683.935: [GC (Allocation Failure) 2019-06-04T18:33:24.612+0800: 26683.935: [ParNew: 415573K->11294K(463872K), 0.0057481 secs] 1150140K->745860K(1554228K), 0.0058815 secs] [Times: user=0.03 sys=0.00, real=0.00 secs]
2019-06-04T18:33:30.032+0800: 26689.355: [GC (Allocation Failure) 2019-06-04T18:33:30.033+0800: 26689.355: [ParNew: 423646K->23998K(463872K), 0.0062182 secs] 1158212K->758564K(1554228K), 0.0063865 secs] [Times: user=0.04 sys=0.00, real=0.00 secs]
2019-06-04T18:33:33.507+0800: 26692.830: [GC (Allocation Failure) 2019-06-04T18:33:33.507+0800: 26692.830: [ParNew: 436311K->35250K(463872K), 0.0047877 secs] 1170877K->769817K(1554228K), 0.0049250 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
2019-06-04T18:33:37.921+0800: 26697.244: [GC (Allocation Failure) 2019-06-04T18:33:37.921+0800: 26697.244: [ParNew: 447602K->9699K(463872K), 0.0079094 secs] 1182169K->752898K(1554228K), 0.0080765 secs] [Times: user=0.04 sys=0.00, real=0.00 secs]
2019-06-04T18:33:49.647+0800: 26708.970: [GC (Allocation Failure) 2019-06-04T18:33:49.647+0800: 26708.970: [ParNew: 422051K->36889K(463872K), 0.0067112 secs] 1165250K->780087K(1554228K), 0.0068562 secs] [Times: user=0.05 sys=0.00, real=0.01 secs]
2019-06-04T18:33:55.591+0800: 26714.914: [GC (Allocation Failure) 2019-06-04T18:33:55.591+0800: 26714.914: [ParNew: 449241K->6015K(463872K), 0.0060338 secs] 1192439K->762115K(1554228K), 0.0061978 secs] [Times: user=0.05 sys=0.00, real=0.00 secs]
2019-06-04T18:34:05.792+0800: 26725.115: [GC (Allocation Failure) 2019-06-04T18:34:05.792+0800: 26725.115: [ParNew: 418367K->11221K(463872K), 0.0070709 secs] 1174467K->767322K(1554228K), 0.0072509 secs] [Times: user=0.04 sys=0.00, real=0.00 secs]
2019-06-04T18:34:16.262+0800: 26735.585: [GC (Allocation Failure) 2019-06-04T18:34:16.263+0800: 26735.585: [ParNew: 423573K->14332K(463872K), 0.0049019 secs] 1179674K->770432K(1554228K), 0.0050493 secs] [Times: user=0.05 sys=0.00, real=0.00 secs]
2019-06-04T18:34:17.823+0800: 26737.146: [GC (Allocation Failure) 2019-06-04T18:34:17.823+0800: 26737.146: [ParNew: 426672K->18534K(463872K), 0.0070292 secs] 1182773K->774634K(1554228K), 0.0071750 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
2019-06-04T18:34:20.759+0800: 26740.082: [GC (Allocation Failure) 2019-06-04T18:34:20.759+0800: 26740.082: [ParNew: 430886K->20395K(463872K), 0.0083357 secs] 1186986K->776495K(1554228K), 0.0084955 secs] [Times: user=0.04 sys=0.00, real=0.01 secs] [/mw_shl_code]
|