Received: by 2002:a25:ef43:0:0:0:0:0 with SMTP id w3csp107452ybm; Tue, 26 May 2020 11:59:26 -0700 (PDT) X-Google-Smtp-Source: ABdhPJwwe/UYnnJ/HQPNKlRfP2hR+Y7eMfMLxk0/6fYdTauPK5/T9OG9Y/26aOsAgRFC2b7+MZue X-Received: by 2002:a17:906:9157:: with SMTP id y23mr2529166ejw.0.1590519566308; Tue, 26 May 2020 11:59:26 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1590519566; cv=none; d=google.com; s=arc-20160816; b=lAHZsiFchapccHpo0qiWsw9o5g998TyfPjGYs7AppuJ7WPNi07UWWojrtpyq9Yy+1c biO/xYZ1kwdYCYW38aKn8hisn7hjgGqQVYDo4oPLKr/vC9rQtvyEpItwPjuspp8rA95X feW+AfuiPyiAzybMvBJ+wGkUvR6NXh9Rx9l6aa2wpsg25cApI76vINJlfx/3zzgomU53 Fk1xSUUsVuZXITILdV6S2ErVTUMxZ1atdvP9pgBZZYSa97SDokLHCPskBmn+ywyuX4/l ND+1ViKjqsZBAKI8Pw8LQfGN4FL9DRCYhfuCmP+dtRHWGvSYmzetGmMfAhz57j0UYeaa hG8g== 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=XV6Rkooi42+DYmAimqTO17Ln5S2pCT5J1jaZmMK+mYg=; b=EGAFUr555sHN45cJUH4LUGBkPymgJXeabaL9+IcXSjibdNeJPNqMu01IGPrFq1V9n+ in6ywHjFh9rYsVzeRbtdUumta62hI+Df71CJ+OzF+NnFqMopphSZlznelaU7UNPcFrn/ YzPe2PAz5uGRixoQlMdDjQRj1IzR1g/Sgi2nNfj4FtslctISPN4vH9ZLLB8mcDciPXcO RzgeokFn7rqYUFW3+HEe98GHRNLAl7ARgWUUMDms2tJHZdmGa1q4+FuNC5WrFMDYeGV4 zPbBd/hgIR/xv83VAVtAj9HIKDJgn2lYAvTrzidIQCLsxwhAj1V31AIlWfWypAZb/77g TK3w== ARC-Authentication-Results: i=1; mx.google.com; dkim=pass header.i=@kernel.org header.s=default header.b=dqgOU5BZ; 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 c9si401806edx.597.2020.05.26.11.59.03; Tue, 26 May 2020 11:59:26 -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=dqgOU5BZ; 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 S2389616AbgEZSzn (ORCPT + 99 others); Tue, 26 May 2020 14:55:43 -0400 Received: from mail.kernel.org ([198.145.29.99]:48130 "EHLO mail.kernel.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S2389600AbgEZSzk (ORCPT ); Tue, 26 May 2020 14:55:40 -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 7763C2084C; Tue, 26 May 2020 18:55:39 +0000 (UTC) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simple; d=kernel.org; s=default; t=1590519339; bh=N6ehpuoCQv6kH0tgSy7p5WhQqA0oIC2CBTQ03EHOEN4=; h=From:To:Cc:Subject:Date:In-Reply-To:References:From; b=dqgOU5BZChjyfdGOmvA/q22jscIcqRgubMi0wSdsUmsygOUw+2bWNN4SmnkOnXgQF btzk/Ll08qhg+1OpCLWhz5g3G3ODYrjZ/Caw4VamUQSt74EindWEjPzOFCcVw68ARw dJ7RU32DAJVRR9v9rs8jajXd0e3WNL52sbsmV3rw= 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 4.4 30/65] ALSA: pcm: fix incorrect hw_base increase Date: Tue, 26 May 2020 20:52:49 +0200 Message-Id: <20200526183917.346073119@linuxfoundation.org> X-Mailer: git-send-email 2.26.2 In-Reply-To: <20200526183905.988782958@linuxfoundation.org> References: <20200526183905.988782958@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 @@ -456,6 +456,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; }