Message-Id: <23103A2E-1BAF-4914-A580-2E118539AD00@goldelico.com>
Date: Wed, 6 Nov 2024 13:16:00 +0100
From: "H. Nikolaus Schaller" <hns@...delico.com>
To: Andreas Kemnade <andreas@...nade.info>,
 Reid Tonking <reidt@...com>
Cc: Tony Lindgren <tony@...mide.com>,
 "Raghavendra, Vignesh" <vigneshr@...com>,
 Aaro Koskinen <aaro.koskinen@....fi>,
 Janusz Krzysztofik <jmkrzyszt@...il.com>,
 Linux-OMAP <linux-omap@...r.kernel.org>,
 linux-i2c@...r.kernel.org,
 Linux Kernel Mailing List <linux-kernel@...r.kernel.org>
Subject: Re: [PATCH v2] i2c: omap: Fix standard mode false ACK readings



> Am 06.11.2024 um 10:23 schrieb Andreas Kemnade <andreas@...nade.info>:
> 
> Am Wed, 11 Sep 2024 11:40:04 +0200
> schrieb "H. Nikolaus Schaller" <hns@...delico.com>:
> 
>> Hi,
>> 
>>> Am 28.04.2023 um 20:30 schrieb Reid Tonking <reidt@...com>:
>>> 
>>> On 10:43-20230428, Tony Lindgren wrote:  
>>>> * Raghavendra, Vignesh <vigneshr@...com> [230427 13:18]:  
>>>>> On 4/27/2023 1:19 AM, Reid Tonking wrote:  
>>>>>> Using standard mode, rare false ACK responses were appearing with
>>>>>> i2cdetect tool. This was happening due to NACK interrupt
>>>>>> triggering ISR thread before register access interrupt was
>>>>>> ready. Removing the NACK interrupt's ability to trigger ISR
>>>>>> thread lets register access ready interrupt do this instead.  
>>>> 
>>>> So is it safe to leave NACK interrupt unhandled until we get the
>>>> next interrupt, does the ARDY always trigger after hitting this?
>>>> 
>>>> Regards,
>>>> 
>>>> Tony  
>>> 
>>> Yep, the ARDY always gets set after a new command when register
>>> access is ready so there's no need for NACK interrupt to control
>>> this.  
>> 
>> I have tested one GTA04A5 board where this patch breaks boot on
>> v4.19.283 or v6.11-rc7 (where it was inherited from some earlier -rc
>> series).
>> 
>> The device is either stuck with no signs of activity or reports RCU
>> stalls after a 20 second pause.
>> 
> Reproduced some problem here:
> i2cset 1 0x69 0x14 0xb6 (reset command for gyro BMG160)
> [  736.136108] omap_i2c 48072000.i2c: addr: 0x0069, len: 2, flags: 0x0,
> stop: 1
> [  736.136322] omap_i2c 48072000.i2c: IRQ (ISR = 0x0010)
> either with this patch applied:
> ... system mostly hangs, i2cset does not return.
> with it reverted:
> ... most times I see after this:
> [  736.136505] omap_i2c 48072000.i2c: IRQ (ISR = 0x0002)
> and i2cset says:
> i2cset: write failed: Remote I/O error
> 
> ... sometimes:
> omap_i2c 48072000.i2c: IRQ (ISR = 0x0004)
> and i2cset is successful.
> 
> Other register writes seem to work reliably, just the reset command.
> I had tested with bmg driver disabled earlier,
> so it did not come to light.

Indeed, I can confirm with your sequence (and bmg driver voluntarily
disabled so that the effect just comes from the i2c bus & client chip).

1. echo blacklist bmg160_i2c >/etc/modprobe.d/test.conf
2. reboot & login:
3. 

Last login: Wed Nov  6 11:24:37 UTC 2024 on ttyO2
root@...ux:~# dmesg|fgrep bmg
root@...ux:~# i2cset -y 1 0x69 0x14 0xb6
root@...ux:~# i2cset -y 1 0x69 0x14 0xb6
root@...ux:~# i2cset -y 1 0x69 0x14 0xb6
root@...ux:~# i2cset -y 1 0x69 0x14 0xb6
--- hangs for some seconds ---
[  109.664245] rcu: INFO: rcu_preempt self-detected stall on CPU
[  109.670318] rcu:     0-...!: (2100 ticks this GP) idle=7e74/1/0x40000004 softirq=9248/9248 fqs=0
[  109.679260] rcu:     (t=2100 jiffies g=11389 q=33 ncpus=1)
[  109.684753] rcu: rcu_preempt kthread timer wakeup didn't happen for 2099 jiffies! g11389 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402
[  109.696685] rcu:     Possible timer handling issue on cpu=0 timer-softirq=4004
[  109.704010] rcu: rcu_preempt kthread starved for 2100 jiffies! g11389 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 ->cpu=0
[  109.714935] rcu:     Unless rcu_preempt kthread gets sufficient CPU time, OOM is now expected behavior.
[  109.724517] rcu: RCU grace-period kthread stack dump:
[  109.729797] task:rcu_preempt     state:I stack:0     pid:15    tgid:15    ppid:2      flags:0x00000000
[  109.739593] Call trace: 
[  109.739593]  __schedule from schedule+0x3c/0x64
[  109.747039]  schedule from schedule_timeout+0xa8/0xd4
[  109.752349]  schedule_timeout from rcu_gp_fqs_loop+0x148/0x370
[  109.758514]  rcu_gp_fqs_loop from rcu_gp_kthread+0xec/0x124
[  109.764373]  rcu_gp_kthread from kthread+0xfc/0x108
[  109.769500]  kthread from ret_from_fork+0x14/0x28
[  109.774444] Exception stack(0xf0041fb0 to 0xf0041ff8)
[  109.779754] 1fa0:                                     00000000 00000000 00000000 00000000
[  109.788330] 1fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  109.796905] 1fe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  109.803863] CPU: 0 UID: 0 PID: 3210 Comm: loginwindow Not tainted 6.12.0-rc6-letux+ #169
[  109.803894] Hardware name: Generic OMAP36xx (Flattened Device Tree)
[  109.803894] PC is at handle_softirqs+0x84/0x300
[  109.803924] LR is at handle_softirqs+0x54/0x300
[  109.803955] pc : [<c0133c3c>]    lr : [<c0133c0c>]    psr: 60070113
[  109.803955] sp : f0001fa0  ip : 844ce392  fp : c0f02080
[  109.803985] r10: f0651be0  r9 : c1008d28  r8 : f0651be8
[  109.803985] r7 : c0f02d40  r6 : 00000200  r5 : c0e91600  r4 : c0e91600
[  109.803985] r3 : 2e70d000  r2 : 00000000  r1 : c0e91600  r0 : c23cad00
[  109.804016] Flags: nZCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment none
[  109.804016] Control: 10c5387d  Table: 82b70019  DAC: 00000051
[  109.804016] Call trace: 
[  109.804046]  handle_softirqs from __irq_exit_rcu+0x6c/0xb4
[  109.804077]  __irq_exit_rcu from irq_exit+0x8/0x10
[  109.804077]  irq_exit from call_with_stack+0x18/0x20
[  109.804138]  call_with_stack from __irq_svc+0x98/0xcc
[  109.804138] Exception stack(0xf0651b60 to 0xf0651ba8)
[  109.804168] 1b60: c2c8f300 f0651ce0 c085aec0 c2c8f300 00000000 00000019 00000000 00000000
[  109.804168] 1b80: f0651be8 00000000 f0651be0 00000000 ffffffff f0651bb0 c02ba850 c085aec0
[  109.804199] 1ba0: a0070113 ffffffff
[  109.804199]  __irq_svc from sock_poll+0x0/0xbc
[  109.804229]  sock_poll from do_sys_poll+0x2a8/0x460
[  109.804260]  do_sys_poll from sys_poll+0x74/0xe8
[  109.804290]  sys_poll from ret_fast_syscall+0x0/0x54
[  109.804290] Exception stack(0xf0651fa8 to 0xf0651ff0)
[  109.804321] 1fa0:                   0000409b 00162f90 beeb07cc 00000001 ffffffff 00000000
[  109.804321] 1fc0: 0000409b 00162f90 b61c3080 000000a8 00000000 00162f9c 00163f90 beeb0874
[  109.804351] 1fe0: 000000a8 beeb07a8 b6a83bd7 b6a057e6

Powered by blists - more mailing lists