Received: by 2002:a25:ef43:0:0:0:0:0 with SMTP id w3csp143983ybm; Tue, 26 May 2020 12:54:54 -0700 (PDT) X-Google-Smtp-Source: ABdhPJx/d4TxYcvbVPWN2BGGkh+bxCviD27oF+UeevFPYJh65GWz1KQM1sxp4NYBSR68JTUu93ag X-Received: by 2002:a17:906:1857:: with SMTP id w23mr2599513eje.273.1590522894237; Tue, 26 May 2020 12:54:54 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1590522894; cv=none; d=google.com; s=arc-20160816; b=wNPObi/21L/CRflmnC5rWqkGKO7ynagsBj1jzd3rDYUbb1dMFVDT468UQbt0PJ4CNu YIEA46jYRqjSNhlW/m1FZq7CbM2H8PlTXQUP52nwenyaiDMPOuaxNYg/LPOQfT/H/Q8C uwEJNVVT8zYadyQjfXZQVAycIIf4fD14/XgPT6eQ/6nBKOKvhgRPTZvMzvUpZWszPPCn ixhRtijUz0SCirBzd0+45zm7DFIyyTmGvXGYfUiwSdknUiLcPywEiDcb++1f8OWdm+ot T3RjOW+oPWum+s33f/IQhzBNDcALMcuuC4jDImuDPcRx0CKNeU6SNjuigv08CUZ12HEL 3ZzQ== ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=arc-20160816; h=list-id:precedence:sender:content-transfer-encoding:mime-version :user-agent:references:in-reply-to:message-id:date:subject:cc:to :from:dkim-signature; bh=MXb8xxvabgKle+hvYmRcBWE5AZ97YjJ5NjdmfgNE2Dc=; b=l8+pgWUpES2SWqTZsN2pwgTRCW4LAukEJblvXMoaBG4V8S46pYY+JHcDtx/jKRaMSz Ki0clKx7+6vFCBzbUlk9MbW3gDzoTj93wn8sw4BEqcHHLHL3ysbcV+JG5+AB+5Jl65ev ElhJdepBvfqyePb8eWYolpRaCrWEcurbudLMcqXD/2wf0Bub9JoaDQk84MPGvLweI03i a87GLfNk9ADQMPhYX1uKHLZqVqJOK6HrpMO3HLvFoBt3AeMouz3XamlAYyvkcyT0UuQO Jm5ymIW8JizzLvCG3zO3f7DfMOouvWE3M79PaWBYVWbN3i2n2I6Im6N37qqOrGeMlDEH 4Qew== ARC-Authentication-Results: i=1; mx.google.com; dkim=pass header.i=@kernel.org header.s=default header.b=wbB0yQoP; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org Return-Path: Received: from vger.kernel.org (vger.kernel.org. [23.128.96.18]) by mx.google.com with ESMTP id b5si465265ejk.381.2020.05.26.12.54.31; Tue, 26 May 2020 12:54:54 -0700 (PDT) Received-SPF: pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) client-ip=23.128.96.18; Authentication-Results: mx.google.com; dkim=pass header.i=@kernel.org header.s=default header.b=wbB0yQoP; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S2391797AbgEZTIa (ORCPT + 99 others); Tue, 26 May 2020 15:08:30 -0400 Received: from mail.kernel.org ([198.145.29.99]:37372 "EHLO mail.kernel.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S2391779AbgEZTI0 (ORCPT ); Tue, 26 May 2020 15:08:26 -0400 Received: from localhost (83-86-89-107.cable.dynamic.v4.ziggo.nl [83.86.89.107]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) by mail.kernel.org (Postfix) with ESMTPSA id BAF6E208A7; Tue, 26 May 2020 19:08:24 +0000 (UTC) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simple; d=kernel.org; s=default; t=1590520105; bh=gTpIT3L94OWBOpYapq2hTCugOTa1hc5f9AexSUrQFn4=; h=From:To:Cc:Subject:Date:In-Reply-To:References:From; b=wbB0yQoP0/+lEoXk7KuUDHdZP2VFMGvYCCCRIVxuOTtztQ7VNwtT5cazs8le/NEq+ Y2ucRwEknR81fPLm/N9U2KCRNDUnCUESn+z0A9YxgsSywPeDP0uLROZ0AQArODTSZW lqFnOcsCvdgnWZO1nwKy1gnc/LVrlK0oxpVHrrNA= From: Greg Kroah-Hartman To: linux-kernel@vger.kernel.org Cc: Greg Kroah-Hartman , stable@vger.kernel.org, Brent Lu , Jaroslav Kysela , Takashi Iwai Subject: [PATCH 5.4 059/111] ALSA: pcm: fix incorrect hw_base increase Date: Tue, 26 May 2020 20:53:17 +0200 Message-Id: <20200526183938.444995252@linuxfoundation.org> X-Mailer: git-send-email 2.26.2 In-Reply-To: <20200526183932.245016380@linuxfoundation.org> References: <20200526183932.245016380@linuxfoundation.org> User-Agent: quilt/0.66 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 8bit Sender: linux-kernel-owner@vger.kernel.org Precedence: bulk List-ID: X-Mailing-List: linux-kernel@vger.kernel.org From: Brent Lu commit e7513c5786f8b33f0c107b3759e433bc6cbb2efa upstream. There is a corner case that ALSA keeps increasing the hw_ptr but DMA already stop working/updating the position for a long time. In following log we can see the position returned from DMA driver does not move at all but the hw_ptr got increased at some point of time so snd_pcm_avail() will return a large number which seems to be a buffer underrun event from user space program point of view. The program thinks there is space in the buffer and fill more data. [ 418.510086] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 4096 avail 12368 [ 418.510149] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 6910 avail 9554 ... [ 418.681052] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 15102 avail 1362 [ 418.681130] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 16464 avail 0 [ 418.726515] sound pcmC0D5p: pos 96 hw_ptr 16464 appl_ptr 16464 avail 16368 This is because the hw_base will be increased by runtime->buffer_size frames unconditionally if the hw_ptr is not updated for over half of buffer time. As the hw_base increases, so does the hw_ptr increased by the same number. The avail value returned from snd_pcm_avail() could exceed the limit (buffer_size) easily becase the hw_ptr itself got increased by same buffer_size samples when the corner case happens. In following log, the buffer_size is 16368 samples but the avail is 21810 samples so CRAS server complains about it. [ 418.851755] sound pcmC0D5p: pos 96 hw_ptr 16464 appl_ptr 27390 avail 5442 [ 418.926491] sound pcmC0D5p: pos 96 hw_ptr 32832 appl_ptr 27390 avail 21810 cras_server[1907]: pcm_avail returned frames larger than buf_size: sof-glkda7219max: :0,5: 21810 > 16368 By updating runtime->hw_ptr_jiffies each time the HWSYNC is called, the hw_base will keep the same when buffer stall happens at long as the interval between each HWSYNC call is shorter than half of buffer time. Following is a log captured by a patched kernel. The hw_base/hw_ptr value is fixed in this corner case and user space program should be aware of the buffer stall and handle it. [ 293.525543] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 4096 avail 12368 [ 293.525606] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 6880 avail 9584 [ 293.525975] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 10976 avail 5488 [ 293.611178] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 15072 avail 1392 [ 293.696429] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 16464 avail 0 ... [ 381.139517] sound pcmC0D5p: pos 96 hw_ptr 96 appl_ptr 16464 avail 0 Signed-off-by: Brent Lu Reviewed-by: Jaroslav Kysela Cc: Link: https://lore.kernel.org/r/1589776238-23877-1-git-send-email-brent.lu@intel.com Signed-off-by: Takashi Iwai Signed-off-by: Greg Kroah-Hartman --- sound/core/pcm_lib.c | 1 + 1 file changed, 1 insertion(+) --- a/sound/core/pcm_lib.c +++ b/sound/core/pcm_lib.c @@ -423,6 +423,7 @@ static int snd_pcm_update_hw_ptr0(struct no_delta_check: if (runtime->status->hw_ptr == new_hw_ptr) { + runtime->hw_ptr_jiffies = curr_jiffies; update_audio_tstamp(substream, &curr_tstamp, &audio_tstamp); return 0; }