tornado服务死锁问题排查

python的tornado框架用到了io多路复用和协程的技术。由于tornado是单线程的异步框架,如果运行过程中产生了阻塞,后续的其它任务也就需要等待。在生产过程中,如果tornado进程产生了假死问题该如何排查呢?

问题描述

运营在使用后台的时候,偶尔会反馈后台报错,而且是大部分时候是好的,只是有时会出问题,刷新之后可能不会报错,可能还会报错。

问题排查

1.nginx

我们在后端服务之前加了nginx代理,所以先查看nginx日志,发现upstream到特定的进程时就会报错,如果其它进程处理则不会报错,而且报错的错误是timeout

2.tornado

后端服务底层框架是tornado。使用curl http://127.0.0.1:port 时超时。查看该进程对应的日志,发现有一段时间没有输出日志了。利用ls -l查看日志最后的更新时间,发现停留在1个小时之前。

3.strace

使用strace -p pid命令查看tornado进程当时的系统调用,输出fcntl(0x...,),并且一直停留在该系统调用。利用fcntl系统调用可以实现文件锁的功能,所以大致可以确定该tornado进程产生了死锁。

4.pyrasite

pyrasite是python进程注入的工具。pip install pyrasite安装之后,pyrasite-shell pid注入到tornado进程,这时进入到python shell的环境

5.sys与traceback

sys._current_frames()返回一个字典,将每个线程的标识符映射到调用该函数时该线程中当前活动的最顶层堆栈帧。
这对于调试死锁是最有用的:这个函数不需要死锁线程的协作,只要这些线程的调用堆栈保持死锁,它们就会被冻结。对于非死锁线程返回的帧在调用代码检查帧时可能与该线程的当前活动没有关系。
traceback.extract_stack(stack)提取栈代码,以及相关的文件路径和行号等

def stacktraces(exclude=()):
    code = []
    current_frames = [(key, value) for key, value in sys._current_frames().items() if key not in exclude]
    for threadId, stack in current_frames:
        code.append("\n# ThreadID: %s" % threadId)
        for filename, lineno, name, line in traceback.extract_stack(stack):
            code.append('File: "%s", line %d, in %s' % (filename, lineno, name))
            if line:
                code.append("  %s" % (line.strip()))
        print threadId
        print code

可执行stacktraces打印tornado线程栈代码

6.tornado log_stack

对5中打印的日志进行分析,确定是由于项目代码在打印日志的时候,tornado进程接到到了打印block log的信号,由于python的logging日志模块是通过thread lock保证线程安全的,在项目代码acquire了tread lock之后但是还未释放时,block log又尝试去acquire thread lock,该调用是阻塞调用,由此产生了死锁。由此也可知在linux平台python的thread lock是通过fcntl系统调用实现的。

后续

知道了问题产生的根本原因,那么这个问题也就解决了一大半了。上述的问题该如何解决呢?可以留个言探讨一下!

追加

之前用到的pyrasite-shell需要注入到python进程中并且还需要执行代码非常不友好,在后续的工作中发现了另一个工具:pystack-debugger,安装之后使用命令pystack pid即可打印出当时的堆栈信息,类似于java的jstack,对于排查python进程死锁问题有非常好的效果。

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

推荐阅读更多精彩内容

  • 一、Python简介和环境搭建以及pip的安装 4课时实验课主要内容 【Python简介】: Python 是一个...
    _小老虎_阅读 5,745评论 0 10
  • 引言 以Django为代表的python web应用部署时采用wsgi协议与服务器对接(被服务器托管),而这类服务...
    大熊_7d48阅读 2,037评论 0 3
  • 一. 操作系统概念 操作系统位于底层硬件与应用软件之间的一层.工作方式: 向下管理硬件,向上提供接口.操作系统进行...
    月亮是我踢弯得阅读 5,967评论 3 28
  • 必备的理论基础 1.操作系统作用: 隐藏丑陋复杂的硬件接口,提供良好的抽象接口。 管理调度进程,并将多个进程对硬件...
    drfung阅读 3,541评论 0 5
  • 我所在的单位因为很多甘肃人,大家看到有些家庭出现老公打老婆的情况,大家就一传十十传百,单位所有的人都知道了:甘肃人...
    五月莲阅读 236评论 0 0