如何查看Django ORM执行的SQL语句

Posted 2021-05-01 BigYoung小站

Django ORM对数据库操作的封装相当完善，日常大部分数据库操作都可以通过ORM实现。

但django将查询过程隐藏在了后台，这在开发时可能会略显晦涩，并且使用方式不当还会造成开销过大。

那么如何查看django何时执行了什么sql语句呢？答案是使用Logging。

先直接上方法，在settings.py中加入LOGGING选项，调整logging等级为DEBUG即可：

LOGGING = { 'version': 1, 'disable_existing_loggers': False, 'formatters': { 'simple': { 'format': '[%(asctime)s] %(message)s' }, }, 'handlers': { 'console': { 'level': 'DEBUG', 'class': 'logging.StreamHandler', 'formatter': 'simple' }, }, 'loggers': { 'django': { 'handlers': ['console'], 'level': 'DEBUG', }, },}

然后启动runserver，浏览需要访问数据库的页面，在shell中即可看见相关日志，如下：

[2018-04-21 21:09:14,676] (0.002) SELECT `blog_article`.`id`, `blog_article`.`title`, `blog_article`.`cover`, `blog_article`.`content`, `blog_article`.`pub_date`, `blog_article`.`category_id`, `blog_article`.`views`, `blog_category`.`id`, `blog_category`.`name` FROM `blog_article` INNER JOIN `blog_category` ON (`blog_article`.`category_id` = `blog_category`.`id`) WHERE `blog_article`.`pub_date` < '2018-04-21 13:09:14.601856' ORDER BY `blog_article`.`pub_date` DESC LIMIT 10; args=('2018-04-21 13:09:14.601856',)[2018-04-21 21:09:14,678] (0.000) SELECT (`blog_article_topics`.`article_id`) AS `_prefetch_related_val_article_id`, `blog_topic`.`id`, `blog_topic`.`name`, `blog_topic`.`number` FROM `blog_topic` INNER JOIN `blog_article_topics` ON (`blog_topic`.`id` = `blog_article_topics`.`topic_id`) WHERE `blog_article_topics`.`article_id` IN (3, 4, 5, 6, 7, 8, 9, 10, 11, 12) ORDER BY `blog_topic`.`number` ASC; args=(3, 4, 5, 6, 7, 8, 9, 10, 11, 12)[2018-04-21 21:09:14,708] "GET / HTTP/1.1" 200 22325

上面打印出的日志是我的博客首页获取前十篇文章时所执行的部分SQL语句，其对应的QuerySet为

Article.objects.filter(pub_date__lt=timezone.now())[:10] \.defer('author', 'category__number') \.select_related('category') \.prefetch_related('topics')

通过Logging不仅可以查看SQL语句，还可以由此知道django何时执行了SQL。在某些情况下我们可以通过这种方式判断，后台是否重复执行了SQL语句，便于指导数据库访问优化。

Django使用Python的内建的logging模块执行系统日志记录。

参考资料

[1]

原文链接: https://zhuanlan.zhihu.com/p/36351635