[리눅스커널] 인터럽트 핸들러 실행 시각 측정 디버깅 패치

가끔 인터럽트 핸들러의 처리 시간이 조금 더 정확히 보고 싶을 때가 있습니다.

이때, 다음과 같은 패치 코드를 적용하면 인터럽트 핸들러의 실행 시간을 측정할 수 있습니다.

 

 

패치 코드는 다음과 같습니다.

 

diff --git a/drivers/mailbox/bcm2835-mailbox.c b/drivers/mailbox/bcm2835-mailbox.c

index a03aeed..e353beb 100644

--- a/drivers/mailbox/bcm2835-mailbox.c

+++ b/drivers/mailbox/bcm2835-mailbox.c

@@ -72,17 +72,29 @@ static struct bcm2835_mbox *bcm2835_link_mbox(struct mbox_chan *link)

        return container_of(link->mbox, struct bcm2835_mbox, controller);

 }

 

+#define HANDLE_TIME 100

+

 static irqreturn_t bcm2835_mbox_irq(int irq, void *dev_id)

 {

+       u64 diff, start_time, curr_time;

        struct bcm2835_mbox *mbox = dev_id;

        struct device *dev = mbox->controller.dev;

        struct mbox_chan *link = &mbox->controller.chans[0];

 

+       start_time = sched_clock();

        while (!(readl(mbox->regs + MAIL0_STA) & ARM_MS_EMPTY)) {

                u32 msg = readl(mbox->regs + MAIL0_RD);

                dev_dbg(dev, "Reply 0x%08X\n", msg);

                mbox_chan_received_data(link, &msg);

        }

+

+       curr_time = sched_clock();

+       diff = curr_time - start_time;

+

+       if (diff > HANDLE_TIME)

+               trace_printk("interrupt: elapsed: %llu, start: %llu \n",

+                               diff, start_time);

+

        return IRQ_HANDLED;

 }

 

코드 내용은 너무 간단합니다. 나노초 단위의 시간 정보를 반환하는 sched_clock() 함수를 사용해서 

실행 시각을 계산하는 코드입니다. 인터럽트 함수의 실행 시간 정보를 저장하는 diff 지역 변수를 ftrace로출력합니다.

 

참고로 인터럽트 핸들러가 실행될 때는 인터럽트 컨텍스트입니다. 빨리 코드가 실행돼야 하니 printk() 함수를 써서 커널 로그를 출력하는 것은 자제해야 합니다.

 

Austin Kim, 궁금한 점이 있으면 댓글로 질문을 남겨주세요.