技术控

    今日:6| 主题:49390
收藏本版 (1)
最新软件应用技术尽在掌握

[其他] Spring MVC注解故障追踪记

[复制链接]
Superficialdial 发表于 2016-9-30 18:37:32
201 4

立即注册CoLaBug.com会员,免费获得投稿人的专业资料,享用更多功能,玩转个人品牌!

您需要 登录 才可以下载或查看,没有帐号?立即注册

x
Spring MVC是美团点评很多团队使用的Web框架。在基于Spring MVC的项目里,注解的使用几乎遍布在项目中的各个模块,有Java提供的注解,如:@[email protected];也有Spring提供的注解,如:@[email protected]@Autowired等;同时还可能有自定义注解等。注解一方面可以作为标记说明使用;另一方面也能帮助我们省去一些配置工作,加快开发速度。注解就像语法糖一样,我有时候会“随心所欲”的把它带入到代码里,一直乐 (hú)此(lǐ)不(hú)疲(tú)[email protected]问题时,才真正意识到乱用注解的危害,同时也有了下文的深入探讨!
  事件起因

  接到业务方需求需要封装上游的一个HTTP接口来提供系统内的服务支持,我封装这个接口并通过本地单元测试后就部署到测试环境中开始测试了。没想到一测试就报NullPointerException异常,异常栈信息如下:
  [code]ERROR [qtp384587033-86] 2015-12-21 16:29:00.905 com.meituan.trip.mobile.hermes.common.utils.HttpClientUtils.doRequest(HttpClientUtils.java:359) HttpClientUtils.doRequest invoke get error, url:nullmt/api/test/v1/query?id=123456
  org.apache.http.client.ClientProtocolException
    at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:186) ~[httpclient-4.3.5.jar:4.3.5]
  …
  Caused by: org.apache.http.ProtocolException: Target host is not specified
  ...[/code]  从异常栈上可以清楚的看出错误原因,是由于请求地址不标准(以 http:// 开头)导致的。这个错误其实很诡异,因为我已经在配置文件中通过XML的方式注入URL属性值了,而且在本地写单元测试都能通过,为什么还会属性注入失败呢?经过反复的检查和尝试,[email protected],问题就会重现,去掉则正常运行。
  问题定位

  [email protected],重新在本地部署并启动工程,从启动日志上发现此实现Bean被替换过:
  [code]INFO [main] 2015-12-21 16:28:47.078 org.springframework.beans.factory.support.DefaultListableBeanFactory.registerBeanDefinition(DefaultListableBeanFactory.java:665) Overriding bean definition for bean 'queryPartnerImpl': replacing [Generic bean: class [com.meituan.trip.mobile.hermes.sal.meilv.impl.QueryPartnerImpl]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in file [/Users/hanzhankang/hermes/hermes-sal/target/classes/com/meituan/trip/mobile/hermes/sal/meilv/impl/QueryPartnerImpl.class]] with [Generic bean: class [com.meituan.trip.mobile.hermes.sal.meilv.impl.QueryPartnerImpl]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [sal/service-outer.xml]][/code]  Spring Bean发生替换是因为在同一个WebApplicationContext下,重复注入同一名称的Bean实例。从上面的日志中我们可以看出,queryPartnerImpl对象最终保留的是通过[sal/service-outer.xml]配置文件注入的Bean,在这个配置文件里详细的设置了相关属性。从替换结果来看,即使发生过替换也不会影响程序到正确运行。那问题会出在哪里呢?
  经过反复调试发现,[email protected],问题就会重现。
  问题排查及解决

  遇到如此诡异的问题,且又不能确定此问题是否是系统其他环境配置导致的时候,不妨可以从这个类在系统中的实例对象身上着手分析,最简单的办法是通过Jmap查询系统中的对象实例个数。
  使用Jmap查询QueryPartnerImpl类在系统中的实例个数及结果:(Jmap是JDK自带的堆分析工具Java Memory Map,可以通过此工具打印出某个Java进程内存内的所有对象大小和数量;建议在测试环境中使用jmap -histo:live命令查询,执行此命令会触发一次Full GC)
  [code]$ jmap -histo:live 20881 | grep QueryPartnerImpl
    1354:             2             80  com.meituan.trip.mobile.hermes.sal.meilv.impl.QueryPartnerImpl[/code]  查看发现系统中居然有2个实例!这和我们对“Spring创建Bean默认是单例的”认知不符,那就把进程Dump出来详细解刨下这2个对象吧!通过Jmap的dump参数把进程镜像dump出来:
  [code]$ jmap -dump:format=b,file=/tmp/heap.bin 20881
  Dumping heap to /private/tmp/dump.data ...
  Heap dump file created[/code]  此时可以使用MAT(内存分析工具,Memory Analysis Tool)并配合Jhat快速定位到此类的实例对象上,通过对象间的引用关系来查找定位原因。
  首先通过Jhat工具来查看QueryPartnerImpl对象及对象间的引用关系:
  [code]$ jhat  /tmp/heap.bin
  ...........................................................................
  Snapshot resolved.
  Started HTTP server on port 7000
  Server is ready.[/code]  (Jhat是JDK自带的堆分析工具Java Heap Analyse Tool,可以将堆中的对象以HTML的形式显示出来,包括对象的数量、大小等,默认端口7000。)
  通过Jhat加载dump文件成功后,访问localhost:7000进入对象列表页,此时通过关键字“QueryPartnerImpl”搜索定位到具体的类上,再点击进去查看详情:
  [code]Class 0x6c36938b0
  class com.meituan.trip.mobile.hermes.sal.meilv.impl.QueryPartnerImpl
  Instances (类的实例)
  Exclude subclasses
  Include subclasses
  References summary by Type(对象的引用关系)
  References summary by type[/code]  点击链接Instances -> Exclude subclasses查看类的实例对象:
   [email protected]6c41b6f80 (64 bytes)
[email protected]7aeafac20 (64 bytes)
  这2个就是QueryPartnerImpl在系统中创建的2个实例对象,点击查看每个对象属性注入情况:
  [code][email protected] (64 bytes)
属性:
clientId (L) : trip_trade (28 bytes)
clientSecret (L) : 6ee952489a93b51b1ffcadd040ca562e (28 bytes)
connectTimeout (I) : 15000
encode (L) : UTF-8 (28 bytes)
log (L) : [email protected] (41 bytes)
readTimeout (I) : 15000
url (L) :  http://test.url.meituan.com/  (28 bytes)

引用关系:
com.meituan.trip.mobile.hermes.biz.cs.GroupTravelCsOrderDetailBiz@0x6c41b6f60 (48 bytes) : field queryPartnerImpl
[email protected] (44 bytes) : field val
[email protected]x6c41b79f0 (66 bytes) : field bean
com.meituan.trip.mobile.hermes.biz.driven.listener.snapshot.Group[email protected] (96 bytes) : field queryPartnerImpl
com.meituan.trip.mobile.hermes.web.controller.api.ApiAliveControl[email protected] (24 bytes) : field queryPartnerImpl

[email protected] (64 bytes)
属性:
clientId (L) :
clientSecret (L) :
connectTimeout (I) : 0
encode (L) :
log (L) : [email protected] (41 bytes)
readTimeout (I) : 0
url (L) :
引用关系:
[email protected]x7aeccfd40 (66 bytes) : field bean
[email protected] (44 bytes) : field val
com.meituan.trip.mobile.hermes.biz.cs.GroupTravelCsOrderDetailBiz@0x7aeafab88 (48 bytes) : field queryPartnerImpl
com.meituan.trip.mobile.hermes.web.controller.api.ApiAliveControl[email protected] (24 bytes) : field queryPartnerImpl
com.meituan.trip.mobile.hermes.biz.driven.listener.snapshot.Group[email protected] (96 bytes) : field queryPartnerImpl[/code]  [email protected]功的,[email protected]这里可以初步判断:导致错误的原因是我们使用的对象是属性注入失败[email protected]
  问题排除到这里,我们不禁有2个疑问:
   1) 为什么会出现2个对象?
  从Spring启动日志看到queryPartnerImpl有被替换的情况,[email protected]用XML定义并注入的Bean,这也只能有1个对象,另一个对象怎么出现的?
   2) 谁在使用这2个对象?
  既然错误已成事实,[email protected]aeafac20呢?而且我们每次都是使用它,[email protected]
  通过Jhat展示的对象引用关系看,只有org.springframework.beans.factory.support.DisposableBeanAdapter和java.util.concurrent.ConcurrentHashMap$Node 比较可疑。但DisposableBeanAdapter是用来管理Spring Bean的销毁,所以和本事故无关,重点就落在java.util.concurrent.ConcurrentHashMap$Node 上了。
  通过MAT工具来分析java.util.concurrent.ConcurrentHashMap$No[email protected],通过对象查找工具并输入对象的内存地址定位:
   
Spring MVC注解故障追踪记-1 (随心所欲,error,ERROR,Java,接口)

  可直接查看此对象:

Spring MVC注解故障追踪记-2 (随心所欲,error,ERROR,Java,接口)

  选中这个对象,右键打开菜单选项,选择:Lists objects -> with incoming references查看都有哪些对象持有此对象(with outgoing references表示此对象拥有哪些对象):
友荐云推荐




上一篇:Docker存储方式选型建议
下一篇:使用 iOS 10 的 Speech 框架构建语音转文本应用
酷辣虫提示酷辣虫禁止发表任何与中华人民共和国法律有抵触的内容!所有内容由用户发布,并不代表酷辣虫的观点,酷辣虫无法对用户发布内容真实性提供任何的保证,请自行验证并承担风险与后果。如您有版权、违规等问题,请通过"联系我们"或"违规举报"告知我们处理。

陌尘ㄨ 发表于 2016-9-30 20:34:37
大神好强大!
回复 支持 反对

使用道具 举报

opli968 发表于 2016-10-1 11:50:28
按“开始键”再按两下“U”可以找出windows的隐藏游戏.
回复 支持 反对

使用道具 举报

让记忆冬眠 发表于 2016-10-15 00:59:42
失去某人,最糟糕的莫过于,他近在身旁,却犹如远在天边.
回复 支持 反对

使用道具 举报

zzzzssss 发表于 2016-11-11 01:53:57
矿难在检讨中继续,楼价在控制中上升。
回复 支持 反对

使用道具 举报

*滑动验证:
您需要登录后才可以回帖 登录 | 立即注册

本版积分规则

我要投稿

推荐阅读

扫码访问 @iTTTTT瑞翔 的微博
回页顶回复上一篇下一篇回列表手机版
手机版/CoLaBug.com ( 粤ICP备05003221号 | 文网文[2010]257号 )|网站地图 酷辣虫

© 2001-2016 Comsenz Inc. Design: Dean. DiscuzFans.

返回顶部 返回列表