如何查看Django ORM執行的SQL語句

05-07

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模塊執行系統日誌記錄。Python在自己的文檔中詳細討論了這個模塊的用法。但是，如果你從未使用過Python的日誌記錄框架(或者即使有)，可以先看看django提供的Logging快速入門。