如何定位内存抖动?StringBuilder.toString()很耗用内存么?

优化方向

内存问题

  • 泄漏
  • 大对象
  • 抖动

页面加载速度

冷热启动

页面卡顿

  • UI刷新框架:事件驱动型,虽然便于管理。
  • 各个链路耗时优化。
  • GC带来暂停。
    • 对象池
    • UI复用

定位大对象、定位内存泄漏点、定位抖动代码点(异步事件、同步事件造成的抖动,掺杂业务复杂度的抖动点最难排查)

一、内存耗用情况分析

1、我们硬件设备的易用性差,因为设备内存小,引发频繁的GC,每次GC占用至少50ms,这个过程会暂停APP进程,导致UI短暂无响应。紧接着如何定位引起抖动的代码位置呢?好难啊!结合Monitor我们能知道点菜大流程存在抖动,但是流程是个复杂的、异步流程,人肉看代码分析,是不可能的,不同于之前代码量少、存在For循环频繁创建对象等场景。

2、如何定位抖动代码位置呢?我们先从定位 抖动对象开始,也就是过程中产生的对象,哪些是有效对象?哪些是无效对象?我们再看是否存在大量耗用内存的无效对象。然后再从对象找到调用链,定位代码位置。

3、如何定位抖动对象呢?我们已知的能够通过record alloc获取一段时间内总共分配了哪些对象、分配的多少次,这是个差量数据!能够通过dumpheap hprof获取当前时间点的内存快照。通过alloc是看不出哪些是临时对象!通过hprof可悲的是内存快照的现在式,抖动是过去式。如果alloc 和 hprof 配合呢? 分析思路又是什么样的呢?

4、不卖官司了,思路如下图。采集两次hprof 和 1次alloc,alloc在两次hprof中间,并且alloc过程要尽可能多的触发抖动,目的是为了扩大特征点,减少干扰。对alloc、hprof进行top排序,两次hprof每一项的的差值应该等于alloc中对应的该项,若存在很大偏差,说明该项存在大量临时对象,为GC扫描加重了负担。

屏幕快照 2018-04-03 下午1.29.58.png
屏幕快照 2018-04-03 下午12.57.57.png
屏幕快照 2018-04-03 下午12.58.02.png

5、对比发现,char[] 耗用内存 11M,String耗用5M。均属于临时对象。下一步,我们看调用链路在哪个点产生的,找到点后,发现都经过了String.format() 和 StringBuilder.append() 。看源码后知道了StringBuilder内部经历了 String -> char[] -> String 2个转化过程,每次转化,都会申请一块新内存,导致产生至少2块同等大小的新内存,新内存都是临时使用的,也就是临时垃圾,会被GC掉的。

屏幕快照 2018-04-03 下午8.27.19.png

6、哎妈呀,终于可以收割了,为保险起见,我又做了三个对比实验,验证StringBuilder.toString()的过程是如何的耗用内存。

7、实验代码如下:

1)
屏幕快照 2018-04-03 下午5.11.25.png

2)
屏幕快照 2018-04-03 下午5.08.42.png

3)
屏幕快照 2018-04-03 下午5.51.20.png
对比结果好可怕:
第一个GC掉10K,第二个GC掉49K,第三个GC掉87K。第二个过程,比第一个多产生40kb。
04-03 16:51:08.397 12602-12608/? D/dalvikvm: GC_EXPLICIT freed <1K, 18% free 3065K/3708K, paused 1ms+1ms, total 17ms 
04-03 16:51:13.997 12602-12608/? D/dalvikvm: GC_EXPLICIT freed 10K, 18% free 3065K/3708K, paused 6ms+2ms, total 50ms 
04-03 16:51:14.517 12602-12608/? D/dalvikvm: GC_EXPLICIT freed <1K, 18% free 3065K/3708K, paused 1ms+1ms, total 14ms 
04-03 16:51:01.167 12602-12608/? D/dalvikvm: GC_EXPLICIT freed <1K, 18% free 3065K/3708K, paused 1ms+1ms, total 15ms 
04-03 16:51:07.447 12602-12608/? D/dalvikvm: GC_EXPLICIT freed 49K, 18% free 3065K/3708K, paused 6ms+3ms, total 53ms 
04-03 16:51:07.987 12602-12608/? D/dalvikvm: GC_EXPLICIT freed <1K, 18% free 3065K/3708K, paused 2ms+1ms, total 15ms 
04-03 17:20:15.067: D/dalvikvm(23173): GC_EXPLICIT freed 87K, 15% free 3062K/3584K, paused 2ms+2ms, total 24ms 
04-03 17:05:15.217: D/dalvikvm(19590): GC_EXPLICIT freed <1K, 15% free 3062K/3572K, paused 2ms+2ms, total 24ms

04-03 16:57:46.707 17926-17926/? D/MainActivity:  builder length  19491  
04-03 16:57:47.467 17926-17926/? D/MainActivity:  msg length  19480  
04-03 16:57:48.027 17926-17926/? D/MainActivity:  builder length  19491
https://blog.csdn.net/liyanjing1987/article/details/38317787

StringBuilder 是否消耗内存

public String toString() {

if (count == 0) {

return "";

}

return StringFactory.newStringFromChars(0, count, value);

}

8、我们代码里,存在大量StringBuilder 和 Format的过程,每个过程,耗用3倍临时内存,经过以下过程,要耗用10倍临时内存。每次GC引发至少50ms的暂停!我们UI响应慢的主要原因。

CalculateManager.calculate

---》 orderCalculateParam.toString(); @1

CalculateLogImpl LOGGER.info("@calculate - CalculateManager {} {}", new Object[]{paramLog, orderCalculateResult});

———》 msg = msg.replaceAll("\\{\\}", "%s");(可忽略不计算)

———》 msg = String.format(msg, objects); @1 @1 @1 String - subString - value[] - String

---》 MessageFormat.format(FORMAT_EXPRESSION, CashierDeskPrefix.M, content); @1 @1

LogMonitor

———》 sb.append(date).append(" | ").append("[").append(DEVICE_TYPE).append("]").append(tag).append(" : ").append(info).append("\n"); @1

———》 sb.toString() @1

———》 bufferedSink.writeUtf8(str); @1

9、解决办法呢?目标是,减少临时垃圾。我们的场景是每个操作到要日志序列化到本地,日志多了之后,对日志管理的过程存在大量格式化拼接,比如StringBuilder、Format,解决办法有两个思路 A\减少类型转化 B\char[]内存复用。

10、char[] 内存复用,及时有应该如何使用呢?

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 203,456评论 5 477
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 85,370评论 2 381
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 150,337评论 0 337
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 54,583评论 1 273
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 63,596评论 5 365
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 48,572评论 1 281
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 37,936评论 3 395
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 36,595评论 0 258
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 40,850评论 1 297
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 35,601评论 2 321
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 37,685评论 1 329
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 33,371评论 4 318
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 38,951评论 3 307
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 29,934评论 0 19
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 31,167评论 1 259
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 43,636评论 2 349
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 42,411评论 2 342

推荐阅读更多精彩内容

  • 在 Java 中,内存的分配是由程序完成的,而内存的释放则是由 Garbage Collecation(GC) 完...
    Shawn_Dut阅读 5,863评论 3 28
  • 1、内存了解 在Android App的性能优化的各个部分里,内存方面的知识较多且不易理解,内存的问题绝对是最令人...
    萧竹阅读 8,752评论 1 12
  • 内存管理的目的就是让我们在开发中怎么有效的避免我们的应用出现内存泄漏的问题。内存泄漏大家都不陌生了,简单粗俗的讲,...
    宇宙只有巴掌大阅读 2,360评论 0 12
  • 小镇老了,这个可以容纳几万人的小镇已经进入垂暮之年了,倚山而立的古镇在岁月中也开始斑驳了它的容颜。长满青苔的青石板...
    只如初見無關風月阅读 271评论 0 1
  • 人的惰性来自哪里呢?内心深处。一、心理抗拒,为懒惰找理由。二、借口宽慰自己。三、接着心安理得玩游戏,看微信之类。...
    MissJi_4502阅读 247评论 0 0