Received: by 2002:a25:ef43:0:0:0:0:0 with SMTP id w3csp123868ybm; Tue, 26 May 2020 12:22:03 -0700 (PDT) X-Google-Smtp-Source: ABdhPJwEOhes+WdHOTM5jUFlD5r3PW2IYP4j2yHMDEk7zGhjzZ9DkcdxuGUN4UpZK/bQr113sVfO X-Received: by 2002:a17:906:2b88:: with SMTP id m8mr2432792ejg.514.1590520923536; Tue, 26 May 2020 12:22:03 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1590520923; cv=none; d=google.com; s=arc-20160816; b=sovgexRSLi/z/wb2z2BZ+mxF7Iipkxb53jBN94DOgKmdtVa4cbb74bS+abtZb+zdyC V12USXj+sajFZDn6OnbvvIgL0FtzSRFeOgCrQ+Ta/PJ+rmvQ4o/yKuOhCbT7KXCS9jBv toqR2WQtmiwxEZxGWEt/UigQyEBfrB9ejHGWjFS0GWdLv4eLaoIBeTvYh6fU4EvzBZ1m UbcL9m2zsO9lTEmqiJpSlPqx60e3ZANzOoq7TXjNovZTDfgzEdCiOoqKmfuSz0EdsjEH KO3V6+NYFvPZe4dHe77uiCky8x7YxHpqc2lZgZqLUMfPbBW+UmS4ns2QuwDXTheFrJOH 0joQ== 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=d4Qs0jNWHEMpVTvAqOX5lwczZbKGqzpCW+PFTeJNDm4=; b=nB9aELnvgGz+GrSePZnypmSe6Zwnccu735SCr+CKdD8mpqt8hTwfZ9wdPAv2TrcsnL NMGZziWDe77m1J6sy3FMW6xlhYvGh+tzaW6oQWf0UgsTr7f+OQ8JnpMWwVv0LpMF9twL 4fAqECpkN/yZVmDny/Pr+qtYegW6ANpvjnV3+cPY6f/42vPP9LxufHFjAq3XywJKuIPz brV+lLAL+OHJP2am+IbxZSRI+NloTPhL/BtjKb80aRxrogHsXXou5MedmSftYnH+K9FL iY2hDKmITs8Y6PW00Bbw1CFfo8UpvnLSRq56QNfjprf3N5UV/GVfo4eKgzHfkb8XulVY WgUw== ARC-Authentication-Results: i=1; mx.google.com; dkim=pass header.i=@kernel.org header.s=default header.b=DwUfoc6G; 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 b17si464118eds.13.2020.05.26.12.21.40; Tue, 26 May 2020 12:22:03 -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=DwUfoc6G; 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 S2404359AbgEZTTA (ORCPT + 99 others); Tue, 26 May 2020 15:19:00 -0400 Received: from mail.kernel.org ([198.145.29.99]:43476 "EHLO mail.kernel.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S2404272AbgEZTN3 (ORCPT ); Tue, 26 May 2020 15:13:29 -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 A00B2208B6; Tue, 26 May 2020 19:13:26 +0000 (UTC) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simple; d=kernel.org; s=default; t=1590520407; bh=iYFk1IVqarP0wBU+3sOEVMVVHNI79VEJUXRz36qO7hs=; h=From:To:Cc:Subject:Date:In-Reply-To:References:From; b=DwUfoc6GtjHdNaUwziGuWXasQLNuc2K7fNveAMN6MTfElPDiY4pNmBcnF+GrskmxX /O89sPGVosHIwsE5+W07+lFiLfJJj6HZUC8bFO8qG1Jo8BmI0mc3n0W+pk+tXjFwpR IP2MUoMp3F423enSX+iFcjzP+iazlC72wxt+/BBA= 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.6 065/126] ALSA: pcm: fix incorrect hw_base increase Date: Tue, 26 May 2020 20:53:22 +0200 Message-Id: <20200526183943.631088293@linuxfoundation.org> X-Mailer: git-send-email 2.26.2 In-Reply-To: <20200526183937.471379031@linuxfoundation.org> References: <20200526183937.471379031@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 @@ -433,6 +433,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; }