ORM이 만든 쿼리를 DB에서 추적하기

SELECT sql_id,
       executions,
       ROUND(elapsed_time / 1000000, 1)                        AS total_sec,
       ROUND(elapsed_time / GREATEST(executions, 1) / 1000, 2) AS avg_ms,
       ROUND(buffer_gets / GREATEST(executions, 1), 1)         AS gets_per_exec,
       SUBSTR(sql_text, 1, 100)                                AS sql_head
FROM   v$sql
WHERE  executions > 1000
AND    elapsed_time / GREATEST(executions, 1) < 10000   -- 한 번은 10ms 미만
ORDER BY elapsed_time DESC
FETCH FIRST 20 ROWS ONLY;

평균은 빠른데 실행 횟수가 수만 번인 단건 조회가 상위에 보이면 N+1을 의심합니다. 화면 한 번 열 때 몇 번 실행되는지 세어 보면 확실해집니다. Part 14의 SQL Trace를 특정 요청에만 켜면 정확한 횟수가 나옵니다.

-- 같은 뿌리에서 나온 변형들이 몇 개나 쌓였는지
SELECT SUBSTR(sql_text, 1, 60) AS sql_head,
       COUNT(*)                AS variant_cnt,
       SUM(executions)         AS total_exec,
       SUM(sharable_mem)       AS mem_bytes
FROM   v$sql
GROUP BY SUBSTR(sql_text, 1, 60)
HAVING COUNT(*) > 20
ORDER BY variant_cnt DESC;

variant_cnt 가 수백 개면 같은 화면의 조건 조합이 전부 별도 문장으로 쌓인 것입니다. 조건 조합이 많은 검색 화면은 어느 정도 불가피하지만, 값까지 문장에 박혀 있다면 그건 바인딩 문제이므로 고쳐야 합니다.

-- MyBatis에서 이렇게 쓰면 값이 문장에 그대로 들어갑니다
--   WHERE customer_id = ${customerId}
-- V$SQL 에 이런 문장이 값만 다르게 수천 개 쌓입니다
SELECT * FROM orders WHERE customer_id = 1001;
SELECT * FROM orders WHERE customer_id = 1002;

-- #{} 를 쓰면 문장 하나가 재사용됩니다
SELECT * FROM orders WHERE customer_id = :1;

값에 문자열 치환을 쓰면 하드 파싱과 SQL 인젝션이 함께 따라옵니다. 정렬 컬럼명이나 테이블명처럼 구조가 바뀌는 자리에만 쓰고, 그때도 허용 목록으로 검증합니다. Spring Boot 코스의 MyBatis 파트와 이어지는 내용입니다.