"release_handler: cannot find top supervisor for application amqp_client"问题排查

问题描述

在热更新的时候,经常发现日志里面出现"release_handler: cannot find top supervisor for application amqp_client"这个日志。

原因分析

erlang 热更新过程涉及到release_handler这个模块,release_handler又调用了release_handler_1这个模块,最终调用到code模块。观察每次生成的relup文件,比如这次的relup文件
<pre>
{"1.3.6",
[{"1.3.5",[],
[{load_object_code,{be3_logic,"1.3.6",
[mall_manager,mod_hero,mongo_mall,
statistics]}},
point_of_no_return,
{load,{mall_manager,brutal_purge,brutal_purge}},
{load,{mod_hero,brutal_purge,brutal_purge}},
{load,{mongo_mall,brutal_purge,brutal_purge}},
{suspend,[statistics]},
{load,{statistics,brutal_purge,brutal_purge}},
{code_change,up,[{statistics,[]}]},
{resume,[statistics]}]}],
[{"1.3.5",[],[point_of_no_return]}]}.
</pre>
热更新的过程,就是release_handler_1.erl解析这个文件,根据指令调用code:load(),code:purge()和erlang:suspend和resume相关进程的过程。
<pre>
eval({load_object_code,)...
eval({point_of_no_return,)...
eval({load,)...
eval({suspend,)...
....
</pre>
问题主要出在suspend这个原子的匹配:即需要suspend进程的时候。
suspend会调用get_supervised_procs()方法
<pre>
get_supervised_procs() ->
lists:foldl(
fun(Application, Procs) ->
get_master_procs(Application,
Procs,
application_controller:get_master(Application))
end,
[],
get_application_names()).

get_supervised_procs(_, Root, Procs, {ok, SupMod}) ->
get_procs(maybe_supervisor_which_children(Root, SupMod, Root), Root) ++
[{undefined, undefined, Root, [SupMod]} | Procs];
get_supervised_procs(Application, Root, Procs, {error, _}) ->
error_logger:error_msg("release_handler: cannot find top supervisor for "
"application wn", [Application]),
get_procs(maybe_supervisor_which_children(Root, Application, Root), Root) ++ Procs.
</pre>

get_supervised_procs(Application, Root, Procs, {error, _})这个分支,已经会打印错误信息了。

get_master_procs/1方法如下
<pre>
get_master_procs(Application, Procs, Pid) when is_pid(Pid) ->
{Root, AppMod} = application_master:get_child(Pid),
get_supervised_procs(Application, Root, Procs, get_supervisor_module(Root));
get_master_procs(
, Procs, _) ->
Procs.
</pre>

get_supervisor_module/1方法如下
<pre>
get_supervisor_module(SupPid) ->
case catch supervisor:get_callback_module(SupPid) of
Module when is_atom(Module) ->
{ok, Module};
_Other ->
io:format("~w: reason: wn", [SupPid, _Other]),
{error, undefined}
end.
</pre>
如果supervisor:get_callback_module()走到了_Other分支,就会返回{error, undefined},从而
get_master_procs()方法打印出我们看见的错误日志。
为什么supervisor:get_callback_module()会走到_Other分支呢?我在节点终端里面直接按照步骤执行如下:
<pre>
(s0@10.10.14.118)1> application_controller:get_master(amqp_client).
<0.1693.0>
(s0@10.10.14.118)2> {Root, _AppMod} = application_master:get_child(pid(0,1693,0)).
{<0.1695.0>,amqp_client}
(s0@10.10.14.118)4> supervisor:get_callback_module(pid(0,1695,0)).
** exception error: {badrecord,state}
</pre>
这里就出错了,被catch住,所以跑到了_Other分支里面。让我们看看<0.1695.0>这个进程的真容吧
<pre>
process_info(pid(0,1695,0)).
[{registered_name,amqp_sup},
{current_function,{gen_server,loop,6}},
{initial_call,{proc_lib,init_p,5}},
{status,waiting},
{message_queue_len,0},
{messages,[]},
{links,[<0.1724.0>,<0.1780.0>,<0.1808.0>,<0.1822.0>,
<0.1794.0>,<0.1752.0>,<0.1766.0>,<0.1738.0>,<0.1696.0>,
<0.1710.0>,<0.1694.0>]},
{dictionary,[{'$ancestors',[<0.1694.0>]},
{'$initial_call',{supervisor2,init,1}}]},
{trap_exit,true},
{error_handler,error_handler},
{priority,normal},
{group_leader,<0.1693.0>},
{total_heap_size,4184},
{heap_size,1598},
{stack_size,9},
{reductions,11292},
{garbage_collection,[{min_bin_vheap_size,46422},
{min_heap_size,233},
{fullsweep_after,65535},
{minor_gcs,1}]},
{suspending,[]}]

</pre>

注意{'$initial_call',{supervisor2,init,1}}]}。看了看amqp_sup,发现的类型是supervisor2,而不是supervisor,而supervisor2的state变量和supervisor的state变量的record定义是不是一样的,后者没有dynamic_restarts。
<pre>
supervisor的state变量
-record(state, {name,
strategy :: strategy(),
children = [] :: [child_rec()],
dynamics :: ?DICT(pid(), list()) | ?SET(pid()),
intensity :: non_neg_integer(),
period :: pos_integer(),
restarts = [],
dynamic_restarts = 0 :: non_neg_integer(),
module,
args}).

supervisor2的state变量
-record(state, {name,
strategy,
children = [],
dynamics = ?DICT:new(),
intensity,
period,
restarts = [],
module,
args}).
</pre>
而get_callback_module方法刚好用了模式匹配,这里向supervisor2调用,所以一定会报错
<pre>
get_callback_module(Pid) ->
{status, _Pid, {module, _Mod},
[_PDict, _SysState, _Parent, _Dbg, Misc]} = sys:get_status(Pid),
[_Header, _Data, {data, [{"State", State}]}] = Misc,
State#state.module.
</pre>

问题处理

这个问题不致命,在logstash加上对于"cannot find top supervisor for application amqp_client"的屏蔽

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

推荐阅读更多精彩内容