2021-03-30 16:58:35

by Naresh Kamboju

[permalink] [raw]
Subject: WARNING: at fs/proc/generic.c:717 remove_proc_entry

While running kselftest gpio on x86_64 and i386 the following warnings were
noticed and the device did not recover.

GOOD: next-20210329
BAD: next-20210330

steps to reproduce:
-------------------
# Boot linux next tag 20210330 on x86_64 machine
# cd /opt/kselftests/default-in-kernel/
# ./gpio-mockup.sh --> which is doing modprobe gpio-mockup

Test log:
------------
# selftests: gpio: gpio-mockup.sh
# 1. Module load tests
# 1.1. dynamic allocation of gpio
# ./gpio-mockup.sh: line 106: ./gpio-mockup-cdev: No such file or directory
# test failed: line value is 127 when 1 was[ 46.136044]
------------[ cut here ]------------
[ 46.141916] remove_proc_entry: removing non-empty directory
'irq/3', leaking at least 'ttyS1'
expected
# GPI[ 46.150471] WARNING: CPU: 2 PID: 603 at
/usr/src/kernel/fs/proc/generic.c:717 remove_proc_entry+0x1a8/0x1c0
[ 46.161566] Modules linked in: gpio_mockup(-) x86_pkg_temp_thermal fuse
[ 46.168195] CPU: 2 PID: 603 Comm: modprobe Not tainted
5.12.0-rc5-next-20210330 #1
[ 46.175793] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS
2.2 05/23/2018
[ 46.183217] RIP: 0010:remove_proc_entry+0x1a8/0x1c0
O gpio-mockup te[ 46.188128] Code: 40 bc 24 a8 48 c7 c7 98 4a 6a a8
48 0f 45 c2 49 8b 94 24 b0 00 00 00 4c 8b 80 d8 00 00 00 48 8b 92 d8
00 00 00 e8 38 36 cb ff <0f> 0b e9 5b ff ff ff e8 0c f4 c5 00 66 90 66
2e 0f 1f 84 00 00 00
[ 46.208252] RSP: 0018:ffff9da080293c58 EFLAGS: 00010286
[ 46.213511] RAX: 0000000000000000 RBX: ffff93e8403bcbb8 RCX: 0000000000000000
[ 46.220650] RDX: 0000000000000001 RSI: ffff93e9afb177f0 RDI: ffff93e9afb177f0
[ 46.227820] RBP: ffff9da080293c88 R08: 0000000000000001 R09: 0000000000000001
[ 46.235018] R10: ffff9da080293a80 R11: ffff9da080293a08 R12: ffff93e8403bcb00
[ 46.242165] R13: ffff93e840263100 R14: 0000000000000001 R15: 0000000000000000
st FAIL
[ 46.249303] FS: 00007f7dc2501740(0000) GS:ffff93e9afb00000(0000)
knlGS:0000000000000000
[ 46.258164] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 46.263977] CR2: 00007f7dc1de67f0 CR3: 000000014f292003 CR4: 00000000003706e0
[ 46.271124] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 46.278292] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 46.285459] Call Trace:
[ 46.287926] unregister_irq_proc+0xf8/0x110
[ 46.292128] free_desc+0x2e/0x70
[ 46.295393] irq_free_descs+0x54/0x80
[ 46.299102] irq_domain_free_irqs+0x11b/0x150
[ 46.303507] irq_dispose_mapping+0x77/0x120
[ 46.307711] gpio_mockup_dispose_mappings+0x4c/0x60 [gpio_mockup]
[ 46.313817] devm_action_release+0x15/0x20
[ 46.317933] release_nodes+0x11e/0x220
[ 46.321707] devres_release_all+0x3c/0x50
[ 46.325733] device_release_driver_internal+0x10e/0x1d0
[ 46.331016] driver_detach+0x4d/0xa0
[ 46.334609] bus_remove_driver+0x5f/0xe0
[ 46.338553] driver_unregister+0x2f/0x50
[ 46.342495] platform_driver_unregister+0x12/0x20
[ 46.347218] gpio_mockup_exit+0x1f/0x5cc [gpio_mockup]
[ 46.352374] __x64_sys_delete_module+0x15b/0x260
[ 46.357082] do_syscall_64+0x37/0x50
[ 46.360676] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 46.365748] RIP: 0033:0x7f7dc1e0f997
[ 46.369342] Code: 73 01 c3 48 8b 0d 01 a5 2b 00 f7 d8 64 89 01 48
83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 b8 b0 00 00
00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d d1 a4 2b 00 f7 d8 64 89
01 48
[ 46.388108] RSP: 002b:00007ffe35506e78 EFLAGS: 00000206 ORIG_RAX:
00000000000000b0
[ 46.395708] RAX: ffffffffffffffda RBX: 000000000134ebd0 RCX: 00007f7dc1e0f997
[ 46.402883] RDX: 0000000000000000 RSI: 0000000000000800 RDI: 000000000134ec38
[ 46.410031] RBP: 0000000000000000 R08: 00007ffe35505e41 R09: 0000000000000000
[ 46.417183] R10: 00000000000008da R11: 0000000000000206 R12: 000000000134ec38
[ 46.424331] R13: 0000000000000001 R14: 000000000134ec38 R15: 00007ffe35508238
[ 46.431507] irq event stamp: 6645
[ 46.434845] hardirqs last enabled at (6655): [<ffffffffa6e4fc4e>]
console_unlock+0x34e/0x550
[ 46.443380] hardirqs last disabled at (6664): [<ffffffffa6e4fcb9>]
console_unlock+0x3b9/0x550
[ 46.451915] softirqs last enabled at (6564): [<ffffffffa800030e>]
__do_softirq+0x30e/0x421
[ 46.460333] softirqs last disabled at (6559): [<ffffffffa6dce643>]
irq_exit_rcu+0xb3/0xc0
[ 46.468548] ---[ end trace 89d0119dab1b1498 ]---
[ 46.477938] remove_proc_entry: removing non-empty directory 'irq/4',
leaking at least 'ttyS0'
[ 46.486512] WARNING: CPU: 0 PID: 603 at
/usr/src/kernel/fs/proc/generic.c:717 remove_proc_entry+0x1a8/0x1c0

Reported-by: Naresh Kamboju <[email protected]>

metadata:
git branch: master
git repo: https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git
git describe: next-20210330
kernel-config:
http://snapshots.linaro.org/openembedded/lkft/lkft/sumo/intel-corei7-64/lkft/linux-next/995/config

Full test log:
https://lkft.validation.linaro.org/scheduler/job/2463479#L1481

https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20210330/testrun/4268028/suite/linux-log-parser/test/check-kernel-exception-2462521/log

--
Linaro LKFT
https://lkft.linaro.org


2021-03-30 17:25:19

by Naresh Kamboju

[permalink] [raw]
Subject: Re: WARNING: at fs/proc/generic.c:717 remove_proc_entry

Small correction,
As per available test data the good tag is next-20210326.
GOOD: next-20210326
BAD: next-20210330

On Tue, 30 Mar 2021 at 22:19, Naresh Kamboju <[email protected]> wrote:
>
> While running kselftest gpio on x86_64 and i386 the following warnings were
> noticed and the device did not recover.
>

GOOD: next-20210326

> BAD: next-20210330
>
> steps to reproduce:
> -------------------
> # Boot linux next tag 20210330 on x86_64 machine
> # cd /opt/kselftests/default-in-kernel/
> # ./gpio-mockup.sh --> which is doing modprobe gpio-mockup
>
> Test log:
> ------------
> # selftests: gpio: gpio-mockup.sh
> # 1. Module load tests
> # 1.1. dynamic allocation of gpio
> # ./gpio-mockup.sh: line 106: ./gpio-mockup-cdev: No such file or directory
> # test failed: line value is 127 when 1 was[ 46.136044]
> ------------[ cut here ]------------
> [ 46.141916] remove_proc_entry: removing non-empty directory
> 'irq/3', leaking at least 'ttyS1'
> expected
> # GPI[ 46.150471] WARNING: CPU: 2 PID: 603 at
> /usr/src/kernel/fs/proc/generic.c:717 remove_proc_entry+0x1a8/0x1c0
> [ 46.161566] Modules linked in: gpio_mockup(-) x86_pkg_temp_thermal fuse
> [ 46.168195] CPU: 2 PID: 603 Comm: modprobe Not tainted
> 5.12.0-rc5-next-20210330 #1
> [ 46.175793] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS
> 2.2 05/23/2018
> [ 46.183217] RIP: 0010:remove_proc_entry+0x1a8/0x1c0
> O gpio-mockup te[ 46.188128] Code: 40 bc 24 a8 48 c7 c7 98 4a 6a a8
> 48 0f 45 c2 49 8b 94 24 b0 00 00 00 4c 8b 80 d8 00 00 00 48 8b 92 d8
> 00 00 00 e8 38 36 cb ff <0f> 0b e9 5b ff ff ff e8 0c f4 c5 00 66 90 66
> 2e 0f 1f 84 00 00 00
> [ 46.208252] RSP: 0018:ffff9da080293c58 EFLAGS: 00010286
> [ 46.213511] RAX: 0000000000000000 RBX: ffff93e8403bcbb8 RCX: 0000000000000000
> [ 46.220650] RDX: 0000000000000001 RSI: ffff93e9afb177f0 RDI: ffff93e9afb177f0
> [ 46.227820] RBP: ffff9da080293c88 R08: 0000000000000001 R09: 0000000000000001
> [ 46.235018] R10: ffff9da080293a80 R11: ffff9da080293a08 R12: ffff93e8403bcb00
> [ 46.242165] R13: ffff93e840263100 R14: 0000000000000001 R15: 0000000000000000
> st FAIL
> [ 46.249303] FS: 00007f7dc2501740(0000) GS:ffff93e9afb00000(0000)
> knlGS:0000000000000000
> [ 46.258164] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 46.263977] CR2: 00007f7dc1de67f0 CR3: 000000014f292003 CR4: 00000000003706e0
> [ 46.271124] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 46.278292] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
> [ 46.285459] Call Trace:
> [ 46.287926] unregister_irq_proc+0xf8/0x110
> [ 46.292128] free_desc+0x2e/0x70
> [ 46.295393] irq_free_descs+0x54/0x80
> [ 46.299102] irq_domain_free_irqs+0x11b/0x150
> [ 46.303507] irq_dispose_mapping+0x77/0x120
> [ 46.307711] gpio_mockup_dispose_mappings+0x4c/0x60 [gpio_mockup]
> [ 46.313817] devm_action_release+0x15/0x20
> [ 46.317933] release_nodes+0x11e/0x220
> [ 46.321707] devres_release_all+0x3c/0x50
> [ 46.325733] device_release_driver_internal+0x10e/0x1d0
> [ 46.331016] driver_detach+0x4d/0xa0
> [ 46.334609] bus_remove_driver+0x5f/0xe0
> [ 46.338553] driver_unregister+0x2f/0x50
> [ 46.342495] platform_driver_unregister+0x12/0x20
> [ 46.347218] gpio_mockup_exit+0x1f/0x5cc [gpio_mockup]
> [ 46.352374] __x64_sys_delete_module+0x15b/0x260
> [ 46.357082] do_syscall_64+0x37/0x50
> [ 46.360676] entry_SYSCALL_64_after_hwframe+0x44/0xae
> [ 46.365748] RIP: 0033:0x7f7dc1e0f997
> [ 46.369342] Code: 73 01 c3 48 8b 0d 01 a5 2b 00 f7 d8 64 89 01 48
> 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 b8 b0 00 00
> 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d d1 a4 2b 00 f7 d8 64 89
> 01 48
> [ 46.388108] RSP: 002b:00007ffe35506e78 EFLAGS: 00000206 ORIG_RAX:
> 00000000000000b0
> [ 46.395708] RAX: ffffffffffffffda RBX: 000000000134ebd0 RCX: 00007f7dc1e0f997
> [ 46.402883] RDX: 0000000000000000 RSI: 0000000000000800 RDI: 000000000134ec38
> [ 46.410031] RBP: 0000000000000000 R08: 00007ffe35505e41 R09: 0000000000000000
> [ 46.417183] R10: 00000000000008da R11: 0000000000000206 R12: 000000000134ec38
> [ 46.424331] R13: 0000000000000001 R14: 000000000134ec38 R15: 00007ffe35508238
> [ 46.431507] irq event stamp: 6645
> [ 46.434845] hardirqs last enabled at (6655): [<ffffffffa6e4fc4e>]
> console_unlock+0x34e/0x550
> [ 46.443380] hardirqs last disabled at (6664): [<ffffffffa6e4fcb9>]
> console_unlock+0x3b9/0x550
> [ 46.451915] softirqs last enabled at (6564): [<ffffffffa800030e>]
> __do_softirq+0x30e/0x421
> [ 46.460333] softirqs last disabled at (6559): [<ffffffffa6dce643>]
> irq_exit_rcu+0xb3/0xc0
> [ 46.468548] ---[ end trace 89d0119dab1b1498 ]---
> [ 46.477938] remove_proc_entry: removing non-empty directory 'irq/4',
> leaking at least 'ttyS0'
> [ 46.486512] WARNING: CPU: 0 PID: 603 at
> /usr/src/kernel/fs/proc/generic.c:717 remove_proc_entry+0x1a8/0x1c0
>
> Reported-by: Naresh Kamboju <[email protected]>
>
> metadata:
> git branch: master
> git repo: https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git
> git describe: next-20210330
> kernel-config:
> http://snapshots.linaro.org/openembedded/lkft/lkft/sumo/intel-corei7-64/lkft/linux-next/995/config
>
> Full test log:
> https://lkft.validation.linaro.org/scheduler/job/2463479#L1481
>
> https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20210330/testrun/4268028/suite/linux-log-parser/test/check-kernel-exception-2462521/log
>
> --
> Linaro LKFT
> https://lkft.linaro.org

2021-03-31 04:38:47

by Andrew Morton

[permalink] [raw]
Subject: Re: WARNING: at fs/proc/generic.c:717 remove_proc_entry

On Tue, 30 Mar 2021 22:19:04 +0530 Naresh Kamboju <[email protected]> wrote:

> While running kselftest gpio on x86_64 and i386 the following warnings were
> noticed and the device did not recover.

I doubt if changes in the core code caused this. Let's cc the
gpoi-mockup developers.

> GOOD: next-20210329
> BAD: next-20210330
>
> steps to reproduce:
> -------------------
> # Boot linux next tag 20210330 on x86_64 machine
> # cd /opt/kselftests/default-in-kernel/
> # ./gpio-mockup.sh --> which is doing modprobe gpio-mockup
>
> Test log:
> ------------
> # selftests: gpio: gpio-mockup.sh
> # 1. Module load tests
> # 1.1. dynamic allocation of gpio
> # ./gpio-mockup.sh: line 106: ./gpio-mockup-cdev: No such file or directory
> # test failed: line value is 127 when 1 was[ 46.136044]
> ------------[ cut here ]------------
> [ 46.141916] remove_proc_entry: removing non-empty directory
> 'irq/3', leaking at least 'ttyS1'
> expected
> # GPI[ 46.150471] WARNING: CPU: 2 PID: 603 at
> /usr/src/kernel/fs/proc/generic.c:717 remove_proc_entry+0x1a8/0x1c0
> [ 46.161566] Modules linked in: gpio_mockup(-) x86_pkg_temp_thermal fuse
> [ 46.168195] CPU: 2 PID: 603 Comm: modprobe Not tainted
> 5.12.0-rc5-next-20210330 #1
> [ 46.175793] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS
> 2.2 05/23/2018
> [ 46.183217] RIP: 0010:remove_proc_entry+0x1a8/0x1c0
> O gpio-mockup te[ 46.188128] Code: 40 bc 24 a8 48 c7 c7 98 4a 6a a8
> 48 0f 45 c2 49 8b 94 24 b0 00 00 00 4c 8b 80 d8 00 00 00 48 8b 92 d8
> 00 00 00 e8 38 36 cb ff <0f> 0b e9 5b ff ff ff e8 0c f4 c5 00 66 90 66
> 2e 0f 1f 84 00 00 00
> [ 46.208252] RSP: 0018:ffff9da080293c58 EFLAGS: 00010286
> [ 46.213511] RAX: 0000000000000000 RBX: ffff93e8403bcbb8 RCX: 0000000000000000
> [ 46.220650] RDX: 0000000000000001 RSI: ffff93e9afb177f0 RDI: ffff93e9afb177f0
> [ 46.227820] RBP: ffff9da080293c88 R08: 0000000000000001 R09: 0000000000000001
> [ 46.235018] R10: ffff9da080293a80 R11: ffff9da080293a08 R12: ffff93e8403bcb00
> [ 46.242165] R13: ffff93e840263100 R14: 0000000000000001 R15: 0000000000000000
> st FAIL
> [ 46.249303] FS: 00007f7dc2501740(0000) GS:ffff93e9afb00000(0000)
> knlGS:0000000000000000
> [ 46.258164] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 46.263977] CR2: 00007f7dc1de67f0 CR3: 000000014f292003 CR4: 00000000003706e0
> [ 46.271124] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 46.278292] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
> [ 46.285459] Call Trace:
> [ 46.287926] unregister_irq_proc+0xf8/0x110
> [ 46.292128] free_desc+0x2e/0x70
> [ 46.295393] irq_free_descs+0x54/0x80
> [ 46.299102] irq_domain_free_irqs+0x11b/0x150
> [ 46.303507] irq_dispose_mapping+0x77/0x120
> [ 46.307711] gpio_mockup_dispose_mappings+0x4c/0x60 [gpio_mockup]
> [ 46.313817] devm_action_release+0x15/0x20
> [ 46.317933] release_nodes+0x11e/0x220
> [ 46.321707] devres_release_all+0x3c/0x50
> [ 46.325733] device_release_driver_internal+0x10e/0x1d0
> [ 46.331016] driver_detach+0x4d/0xa0
> [ 46.334609] bus_remove_driver+0x5f/0xe0
> [ 46.338553] driver_unregister+0x2f/0x50
> [ 46.342495] platform_driver_unregister+0x12/0x20
> [ 46.347218] gpio_mockup_exit+0x1f/0x5cc [gpio_mockup]
> [ 46.352374] __x64_sys_delete_module+0x15b/0x260
> [ 46.357082] do_syscall_64+0x37/0x50
> [ 46.360676] entry_SYSCALL_64_after_hwframe+0x44/0xae
> [ 46.365748] RIP: 0033:0x7f7dc1e0f997
> [ 46.369342] Code: 73 01 c3 48 8b 0d 01 a5 2b 00 f7 d8 64 89 01 48
> 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 b8 b0 00 00
> 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d d1 a4 2b 00 f7 d8 64 89
> 01 48
> [ 46.388108] RSP: 002b:00007ffe35506e78 EFLAGS: 00000206 ORIG_RAX:
> 00000000000000b0
> [ 46.395708] RAX: ffffffffffffffda RBX: 000000000134ebd0 RCX: 00007f7dc1e0f997
> [ 46.402883] RDX: 0000000000000000 RSI: 0000000000000800 RDI: 000000000134ec38
> [ 46.410031] RBP: 0000000000000000 R08: 00007ffe35505e41 R09: 0000000000000000
> [ 46.417183] R10: 00000000000008da R11: 0000000000000206 R12: 000000000134ec38
> [ 46.424331] R13: 0000000000000001 R14: 000000000134ec38 R15: 00007ffe35508238
> [ 46.431507] irq event stamp: 6645
> [ 46.434845] hardirqs last enabled at (6655): [<ffffffffa6e4fc4e>]
> console_unlock+0x34e/0x550
> [ 46.443380] hardirqs last disabled at (6664): [<ffffffffa6e4fcb9>]
> console_unlock+0x3b9/0x550
> [ 46.451915] softirqs last enabled at (6564): [<ffffffffa800030e>]
> __do_softirq+0x30e/0x421
> [ 46.460333] softirqs last disabled at (6559): [<ffffffffa6dce643>]
> irq_exit_rcu+0xb3/0xc0
> [ 46.468548] ---[ end trace 89d0119dab1b1498 ]---
> [ 46.477938] remove_proc_entry: removing non-empty directory 'irq/4',
> leaking at least 'ttyS0'
> [ 46.486512] WARNING: CPU: 0 PID: 603 at
> /usr/src/kernel/fs/proc/generic.c:717 remove_proc_entry+0x1a8/0x1c0
>
> Reported-by: Naresh Kamboju <[email protected]>
>
> metadata:
> git branch: master
> git repo: https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git
> git describe: next-20210330
> kernel-config:
> http://snapshots.linaro.org/openembedded/lkft/lkft/sumo/intel-corei7-64/lkft/linux-next/995/config
>
> Full test log:
> https://lkft.validation.linaro.org/scheduler/job/2463479#L1481
>
> https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20210330/testrun/4268028/suite/linux-log-parser/test/check-kernel-exception-2462521/log
>
> --
> Linaro LKFT
> https://lkft.linaro.org