linux-kernel.vger.kernel.org archive mirror
 help / color / mirror / Atom feed
From: Kever Yang <kever.yang@rock-chips.com>
To: Douglas Anderson <dianders@chromium.org>,
	John Youn <John.Youn@synopsys.com>,
	balbi@ti.com
Cc: huangtao@rock-chips.com, gregory.herrero@intel.com,
	heiko@sntech.de, johnyoun@synopsys.com,
	gregkh@linuxfoundation.org, ming.lei@canonical.com,
	linux-usb@vger.kernel.org, linux-kernel@vger.kernel.org,
	linux-rockchip@lists.infradead.org, yousaf.kaukab@intel.com,
	stern@rowland.harvard.edu, william.wu@rock-chips.com,
	Julius Werner <jwerner@chromium.org>,
	dinguyen@opensource.altera.com
Subject: Re: [PATCH v5 08/21] usb: dwc2: host: Add scheduler tracing
Date: Thu, 28 Jan 2016 11:39:14 +0800	[thread overview]
Message-ID: <56A98D62.8040505@rock-chips.com> (raw)
In-Reply-To: <1453486736-15358-9-git-send-email-dianders@chromium.org>

Hi Doug,

     Very good idea here, I used to add some local patch and with USB
analyzer to debug this part of code, and we can use more tools with
your patch now, I can try kernelshark next time.

     Do you consider to add tracing for qtd? Then we can track the whole
lift cycle of urb in dwc2 driver.

     For this patch:
Reviewed-by: Kever Yang <kever.yang@rock-chips.com>

Thanks,
- Kever
On 01/23/2016 02:18 AM, Douglas Anderson wrote:
> In preparation for future changes to the scheduler let's add some
> tracing that makes it easy for us to see what's happening.  By default
> this tracing will be off.
>
> By changing "core.h" you can easily trace to ftrace, the console, or
> nowhere.
>
> Signed-off-by: Douglas Anderson <dianders@chromium.org>
> ---
> Changes in v5: None
> Changes in v4:
> - Retooled scheduler tracing a bit, so left off John's Ack from v3.
>
> Changes in v3: None
> Changes in v2: None
>
>   drivers/usb/dwc2/core.h      | 20 ++++++++++++++++++++
>   drivers/usb/dwc2/hcd.h       |  5 +++++
>   drivers/usb/dwc2/hcd_intr.c  |  6 +++++-
>   drivers/usb/dwc2/hcd_queue.c | 24 +++++++++++++++++++++++-
>   4 files changed, 53 insertions(+), 2 deletions(-)
>
> diff --git a/drivers/usb/dwc2/core.h b/drivers/usb/dwc2/core.h
> index 538cf38af0e4..18f9e4045643 100644
> --- a/drivers/usb/dwc2/core.h
> +++ b/drivers/usb/dwc2/core.h
> @@ -44,6 +44,26 @@
>   #include <linux/usb/phy.h>
>   #include "hw.h"
>   
> +/*
> + * Suggested defines for tracers:
> + * - no_printk:    Disable tracing
> + * - pr_info:      Print this info to the console
> + * - trace_printk: Print this info to trace buffer (good for verbose logging)
> + */
> +
> +#define DWC2_TRACE_SCHEDULER		no_printk
> +#define DWC2_TRACE_SCHEDULER_VB		no_printk
> +
> +/* Detailed scheduler tracing, but won't overwhelm console */
> +#define dwc2_sch_dbg(hsotg, fmt, ...)					\
> +	DWC2_TRACE_SCHEDULER(pr_fmt("%s: SCH: " fmt),			\
> +			     dev_name(hsotg->dev), ##__VA_ARGS__)
> +
> +/* Verbose scheduler tracing */
> +#define dwc2_sch_vdbg(hsotg, fmt, ...)					\
> +	DWC2_TRACE_SCHEDULER_VB(pr_fmt("%s: SCH: " fmt),		\
> +				dev_name(hsotg->dev), ##__VA_ARGS__)
> +
>   static inline u32 dwc2_readl(const void __iomem *addr)
>   {
>   	u32 value = __raw_readl(addr);
> diff --git a/drivers/usb/dwc2/hcd.h b/drivers/usb/dwc2/hcd.h
> index 1b46e2e617cc..809bc4ff9116 100644
> --- a/drivers/usb/dwc2/hcd.h
> +++ b/drivers/usb/dwc2/hcd.h
> @@ -563,6 +563,11 @@ static inline u16 dwc2_frame_num_inc(u16 frame, u16 inc)
>   	return (frame + inc) & HFNUM_MAX_FRNUM;
>   }
>   
> +static inline u16 dwc2_frame_num_dec(u16 frame, u16 dec)
> +{
> +	return (frame + HFNUM_MAX_FRNUM + 1 - dec) & HFNUM_MAX_FRNUM;
> +}
> +
>   static inline u16 dwc2_full_frame_num(u16 frame)
>   {
>   	return (frame & HFNUM_MAX_FRNUM) >> 3;
> diff --git a/drivers/usb/dwc2/hcd_intr.c b/drivers/usb/dwc2/hcd_intr.c
> index 577c91096a51..5d25a5ec9736 100644
> --- a/drivers/usb/dwc2/hcd_intr.c
> +++ b/drivers/usb/dwc2/hcd_intr.c
> @@ -138,13 +138,17 @@ static void dwc2_sof_intr(struct dwc2_hsotg *hsotg)
>   	while (qh_entry != &hsotg->periodic_sched_inactive) {
>   		qh = list_entry(qh_entry, struct dwc2_qh, qh_list_entry);
>   		qh_entry = qh_entry->next;
> -		if (dwc2_frame_num_le(qh->sched_frame, hsotg->frame_number))
> +		if (dwc2_frame_num_le(qh->sched_frame, hsotg->frame_number)) {
> +			dwc2_sch_vdbg(hsotg, "QH=%p ready fn=%04x, sch=%04x\n",
> +				      qh, hsotg->frame_number, qh->sched_frame);
> +
>   			/*
>   			 * Move QH to the ready list to be executed next
>   			 * (micro)frame
>   			 */
>   			list_move_tail(&qh->qh_list_entry,
>   				  &hsotg->periodic_sched_ready);
> +		}
>   	}
>   	tr_type = dwc2_hcd_select_transactions(hsotg);
>   	if (tr_type != DWC2_TRANSACTION_NONE)
> diff --git a/drivers/usb/dwc2/hcd_queue.c b/drivers/usb/dwc2/hcd_queue.c
> index bc632a72f611..0e9faa75593c 100644
> --- a/drivers/usb/dwc2/hcd_queue.c
> +++ b/drivers/usb/dwc2/hcd_queue.c
> @@ -113,6 +113,9 @@ static void dwc2_qh_init(struct dwc2_hsotg *hsotg, struct dwc2_qh *qh,
>   		qh->sched_frame = dwc2_frame_num_inc(hsotg->frame_number,
>   						     SCHEDULE_SLOP);
>   		qh->interval = urb->interval;
> +		dwc2_sch_dbg(hsotg, "QH=%p init sch=%04x, fn=%04x, int=%#x\n",
> +			     qh, qh->sched_frame, hsotg->frame_number,
> +			     qh->interval);
>   #if 0
>   		/* Increase interrupt polling rate for debugging */
>   		if (qh->ep_type == USB_ENDPOINT_XFER_INT)
> @@ -126,6 +129,11 @@ static void dwc2_qh_init(struct dwc2_hsotg *hsotg, struct dwc2_qh *qh,
>   			qh->interval *= 8;
>   			qh->sched_frame |= 0x7;
>   			qh->start_split_frame = qh->sched_frame;
> +			dwc2_sch_dbg(hsotg,
> +				     "QH=%p init*8 sch=%04x, fn=%04x, int=%#x\n",
> +				     qh, qh->sched_frame, hsotg->frame_number,
> +				     qh->interval);
> +
>   		}
>   		dev_dbg(hsotg->dev, "interval=%d\n", qh->interval);
>   	}
> @@ -482,6 +490,8 @@ static int dwc2_schedule_periodic(struct dwc2_hsotg *hsotg, struct dwc2_qh *qh)
>   		if (frame >= 0) {
>   			qh->sched_frame &= ~0x7;
>   			qh->sched_frame |= (frame & 7);
> +			dwc2_sch_dbg(hsotg, "QH=%p sched_p sch=%04x, uf=%d\n",
> +				     qh, qh->sched_frame, frame);
>   		}
>   
>   		if (status > 0)
> @@ -583,10 +593,16 @@ int dwc2_hcd_qh_add(struct dwc2_hsotg *hsotg, struct dwc2_qh *qh)
>   
>   	if (!dwc2_frame_num_le(qh->sched_frame, hsotg->frame_number) &&
>   			!hsotg->frame_number) {
> +		u16 new_frame;
> +
>   		dev_dbg(hsotg->dev,
>   				"reset frame number counter\n");
> -		qh->sched_frame = dwc2_frame_num_inc(hsotg->frame_number,
> +		new_frame = dwc2_frame_num_inc(hsotg->frame_number,
>   				SCHEDULE_SLOP);
> +
> +		dwc2_sch_vdbg(hsotg, "QH=%p reset sch=%04x=>%04x\n",
> +			      qh, qh->sched_frame, new_frame);
> +		qh->sched_frame = new_frame;
>   	}
>   
>   	/* Add the new QH to the appropriate schedule */
> @@ -652,6 +668,7 @@ static void dwc2_sched_periodic_split(struct dwc2_hsotg *hsotg,
>   				      int sched_next_periodic_split)
>   {
>   	u16 incr;
> +	u16 old_frame = qh->sched_frame;
>   
>   	if (sched_next_periodic_split) {
>   		qh->sched_frame = frame_number;
> @@ -677,6 +694,11 @@ static void dwc2_sched_periodic_split(struct dwc2_hsotg *hsotg,
>   		qh->sched_frame |= 0x7;
>   		qh->start_split_frame = qh->sched_frame;
>   	}
> +
> +	dwc2_sch_vdbg(hsotg, "QH=%p next(%d) fn=%04x, sch=%04x=>%04x (%+d)\n",
> +		      qh, sched_next_periodic_split, frame_number, old_frame,
> +		      qh->sched_frame,
> +		      dwc2_frame_num_dec(qh->sched_frame, old_frame));
>   }
>   
>   /*

  reply	other threads:[~2016-01-28  3:39 UTC|newest]

Thread overview: 40+ messages / expand[flat|nested]  mbox.gz  Atom feed  top
2016-01-22 18:18 [PATCH v5 0/21] usb: dwc2: host: Fix and speed up all the stuff, especially with splits Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 01/21] usb: dwc2: rockchip: Make the max_transfer_size automatic Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 02/21] usb: dwc2: host: Get aligned DMA in a more supported way Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 03/21] usb: dwc2: host: Set host_rx_fifo_size to 528 for rk3066 Douglas Anderson
2016-01-27 10:13   ` Kever Yang
2016-01-27 19:44     ` Doug Anderson
2016-01-28  8:28       ` Kever Yang
2016-01-22 18:18 ` [PATCH v5 04/21] usb: dwc2: host: Set host_perio_tx_fifo_size to 304 " Douglas Anderson
2016-01-28  3:10   ` Kever Yang
2016-01-28  3:28     ` Doug Anderson
2016-01-28  6:41       ` Kever Yang
2016-01-28 18:16         ` Doug Anderson
2016-01-28 23:25           ` Doug Anderson
2016-01-22 18:18 ` [PATCH v5 05/21] usb: dwc2: host: Avoid use of chan->qh after qh freed Douglas Anderson
2016-01-28  3:25   ` Kever Yang
2016-01-28 23:26     ` Doug Anderson
2016-01-22 18:18 ` [PATCH v5 06/21] usb: dwc2: host: Always add to the tail of queues Douglas Anderson
2016-01-27 10:23   ` Kever Yang
2016-01-22 18:18 ` [PATCH v5 07/21] usb: dwc2: hcd: fix split transfer schedule sequence Douglas Anderson
2016-01-28  1:20   ` Kever Yang
2016-01-22 18:18 ` [PATCH v5 08/21] usb: dwc2: host: Add scheduler tracing Douglas Anderson
2016-01-28  3:39   ` Kever Yang [this message]
2016-01-22 18:18 ` [PATCH v5 09/21] usb: dwc2: host: Add a delay before releasing periodic bandwidth Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 10/21] usb: dwc2: host: Giveback URB in tasklet context Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 11/21] usb: dwc2: host: Use periodic interrupt even with DMA Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 12/21] usb: dwc2: host: Rename some fields in struct dwc2_qh Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 13/21] usb: dwc2: host: Reorder things in hcd_queue.c Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 14/21] usb: dwc2: host: Split code out to make dwc2_do_reserve() Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 15/21] usb: dwc2: host: Add scheduler logging for missed SOFs Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 16/21] usb: dwc2: host: Manage frame nums better in scheduler Douglas Anderson
2016-01-27 20:49   ` Doug Anderson
2016-01-22 18:18 ` [PATCH v5 17/21] usb: dwc2: host: Schedule periodic right away if it's time Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 18/21] usb: dwc2: host: Add dwc2_hcd_get_future_frame_number() call Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 19/21] usb: dwc2: host: Properly set even/odd frame Douglas Anderson
2016-01-22 18:18 ` [PATCH v5 20/21] usb: dwc2: host: Totally redo the microframe scheduler Douglas Anderson
2016-01-24  5:44   ` Doug Anderson
2016-01-22 18:18 ` [PATCH v5 21/21] usb: dwc2: host: If using uframe scheduler, end splits better Douglas Anderson
2016-01-23 17:52 ` [PATCH v5 0/21] usb: dwc2: host: Fix and speed up all the stuff, especially with splits Heiko Stuebner
2016-01-23 23:09   ` Doug Anderson
2016-01-24  5:36     ` Doug Anderson

Reply instructions:

You may reply publicly to this message via plain-text email
using any one of the following methods:

* Save the following mbox file, import it into your mail client,
  and reply-to-all from there: mbox

  Avoid top-posting and favor interleaved quoting:
  https://en.wikipedia.org/wiki/Posting_style#Interleaved_style

* Reply using the --to, --cc, and --in-reply-to
  switches of git-send-email(1):

  git send-email \
    --in-reply-to=56A98D62.8040505@rock-chips.com \
    --to=kever.yang@rock-chips.com \
    --cc=John.Youn@synopsys.com \
    --cc=balbi@ti.com \
    --cc=dianders@chromium.org \
    --cc=dinguyen@opensource.altera.com \
    --cc=gregkh@linuxfoundation.org \
    --cc=gregory.herrero@intel.com \
    --cc=heiko@sntech.de \
    --cc=huangtao@rock-chips.com \
    --cc=johnyoun@synopsys.com \
    --cc=jwerner@chromium.org \
    --cc=linux-kernel@vger.kernel.org \
    --cc=linux-rockchip@lists.infradead.org \
    --cc=linux-usb@vger.kernel.org \
    --cc=ming.lei@canonical.com \
    --cc=stern@rowland.harvard.edu \
    --cc=william.wu@rock-chips.com \
    --cc=yousaf.kaukab@intel.com \
    /path/to/YOUR_REPLY

  https://kernel.org/pub/software/scm/git/docs/git-send-email.html

* If your mail client supports setting the In-Reply-To header
  via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line before the message body.
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).