Re: [LKP] [rcutorture] 894b343aa8: WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog
On Fri, Sep 07, 2018 at 11:01:56AM +0800, kernel test robot wrote:
> FYI, we noticed the following commit (built with gcc-7):
>
> commit: 894b343aa8bec5ee732329f1db09b9f5c2794de5 ("rcutorture: Add call_rcu()
> flooding forward-progress tests")
> https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git
> dev.2018.08.30a
>
> in testcase: trinity
> with following parameters:
>
> runtime: 300s
>
> test-description: Trinity is a linux system call fuzz tester.
> test-url: http://codemonkey.org.uk/projects/trinity/
This one is real, and I have been working on it.
I added forward-progress testing, and am still working out the kinks.
It might be a little bit.
Thanx, Paul
> on test machine: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -smp
> 2 -m 512M
>
> caused below changes (please refer to attached dmesg/kmsg for entire
> log/backtrace):
>
>
> +--+++
> | |
> 93fd89934b | 894b343aa8 |
> +--+++
> | boot_successes | 24
> | 18 |
> | boot_failures| 1
> | 12 |
> | invoked_oom-killer:gfp_mask=0x | 1
> | 2 |
> | Mem-Info | 1
> | 2 |
> | Out_of_memory:Kill_process | 1
> ||
> | Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 0
> | 2 |
> | RIP:rcu_torture_fwd_prog | 0
> | 10 |
> | WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog | 0
> | 9 |
> +--+++
>
>
>
> [ 307.810166] WARNING: CPU: 1 PID: 54 at kernel/rcu/rcutorture.c:1840
> rcu_torture_fwd_prog+0x41f/0x542
> [ 307.832010] Modules linked in:
> [ 307.837737] CPU: 1 PID: 54 Comm: rcu_torture_fwd Tainted: G
> T 4.19.0-rc1-00151-g894b343 #1
> [ 307.856076] RIP: 0010:rcu_torture_fwd_prog+0x41f/0x542
> [ 307.866058] Code: b8 0e 00 eb e2 48 c7 05 c9 25 35 01 f0 fa 78 83 c6 05 92
> 99 67 02 00 e8 29 6c 09 00 84 c0 0f 85 af 00 00 00 49 83 fc 63 7f 02 <0f> 0b
> 48 8b 45 88 4f 8d 44 3d 00 4d 89 e9 48 c7 c6 a0 34 e2 81 48
> [ 307.902163] RSP: 0018:88000c1cbe80 EFLAGS: 00010293
> [ 307.912698] RAX: RBX: 0001 RCX:
> 88001046c080
> [ 307.926369] RDX: 0017 RSI: 811c173d RDI:
> 88001046c080
> [ 307.940082] RBP: 88000c1cbf00 R08: 0020 R09:
> 8800053e4ce0
> [ 307.953984] R10: 8800053e4260 R11: 8800053e4d40 R12:
>
> [ 307.968462] R13: 0003 R14: R15:
> 0083
> [ 307.982466] FS: () GS:88001c40()
> knlGS:
> [ 307.998082] CS: 0010 DS: ES: CR0: 80050033
> [ 308.009554] CR2: 7ffc7538e000 CR3: 02411004 CR4:
> 000206a0
> [ 308.023264] Call Trace:
> [ 308.028512] ? _raw_spin_unlock_irqrestore+0x45/0x4f
> [ 308.038529] ? rcu_torture_stall+0x12d/0x12d
> [ 308.047149] ? kthread+0x114/0x123
> [ 308.054115] ? kthread+0x114/0x123
> [ 308.060625] ? kthread_create_worker_on_cpu+0x5f/0x5f
> [ 308.069703] ? ret_from_fork+0x3a/0x50
> [ 308.076537] irq event stamp: 3048
> [ 308.082507] hardirqs last enabled at (3047): []
> kfree+0x125/0x136
> [ 308.097831] hardirqs last disabled at (3048): []
> trace_hardirqs_off_thunk+0x1a/0x1c
> [ 308.115656] softirqs last enabled at (56): []
> __do_softirq+0x359/0x39b
> [ 308.130979] softirqs last disabled at (49): []
> irq_exit+0x59/0x75
> [ 308.145115] ---[ end trace 3654c8b0e1b99cb1 ]---
>
>
> To reproduce:
>
> git clone https://github.com/intel/lkp-tests.git
> cd lkp-tests
> bin/lkp qemu -k job-script # job-script is attached in this
> email
>
>
>
> Thanks,
> Rong, Chen
> #
> # Automatically generated file; DO NOT EDIT.
> # Linux/x86_64 4.19.0-rc1 Kernel Configuration
> #
>
> #
> # Compiler: gcc-7 (Debian 7.3.0-16) 7.3.0
> #
> CONFIG_CC_IS_GCC=y
> CONFIG_GCC_VERSION=70300
> CONFIG_CLANG_VERSION=0
> CONFIG_CONSTRUCTORS=y
> CONFIG_IRQ_WORK=y
> CONFIG_BUILDTIME_EXTABLE_SORT=y
> CONFIG_THREAD_INFO_IN_TASK=y
>
> #
> # General setup
> #
> CONFIG_INIT_ENV_ARG_LIMIT=32
> # CONFIG_COMPILE_TEST is not set
> CONFIG_LOCALVERSION=""
> CONFIG_LOCALVERSION_AUTO=y
> CONFIG_BUILD_SALT=""
> CONFIG_HAVE_KERNEL_GZIP=y
> CONFIG_HAVE_KERNEL_BZIP2=y
> CONFIG_HAVE_KERNEL_LZMA=y
>
Re: [LKP] [rcutorture] 894b343aa8: WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog
On Fri, Sep 07, 2018 at 11:01:56AM +0800, kernel test robot wrote:
> FYI, we noticed the following commit (built with gcc-7):
>
> commit: 894b343aa8bec5ee732329f1db09b9f5c2794de5 ("rcutorture: Add call_rcu()
> flooding forward-progress tests")
> https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git
> dev.2018.08.30a
>
> in testcase: trinity
> with following parameters:
>
> runtime: 300s
>
> test-description: Trinity is a linux system call fuzz tester.
> test-url: http://codemonkey.org.uk/projects/trinity/
This one is real, and I have been working on it.
I added forward-progress testing, and am still working out the kinks.
It might be a little bit.
Thanx, Paul
> on test machine: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -smp
> 2 -m 512M
>
> caused below changes (please refer to attached dmesg/kmsg for entire
> log/backtrace):
>
>
> +--+++
> | |
> 93fd89934b | 894b343aa8 |
> +--+++
> | boot_successes | 24
> | 18 |
> | boot_failures| 1
> | 12 |
> | invoked_oom-killer:gfp_mask=0x | 1
> | 2 |
> | Mem-Info | 1
> | 2 |
> | Out_of_memory:Kill_process | 1
> ||
> | Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 0
> | 2 |
> | RIP:rcu_torture_fwd_prog | 0
> | 10 |
> | WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog | 0
> | 9 |
> +--+++
>
>
>
> [ 307.810166] WARNING: CPU: 1 PID: 54 at kernel/rcu/rcutorture.c:1840
> rcu_torture_fwd_prog+0x41f/0x542
> [ 307.832010] Modules linked in:
> [ 307.837737] CPU: 1 PID: 54 Comm: rcu_torture_fwd Tainted: G
> T 4.19.0-rc1-00151-g894b343 #1
> [ 307.856076] RIP: 0010:rcu_torture_fwd_prog+0x41f/0x542
> [ 307.866058] Code: b8 0e 00 eb e2 48 c7 05 c9 25 35 01 f0 fa 78 83 c6 05 92
> 99 67 02 00 e8 29 6c 09 00 84 c0 0f 85 af 00 00 00 49 83 fc 63 7f 02 <0f> 0b
> 48 8b 45 88 4f 8d 44 3d 00 4d 89 e9 48 c7 c6 a0 34 e2 81 48
> [ 307.902163] RSP: 0018:88000c1cbe80 EFLAGS: 00010293
> [ 307.912698] RAX: RBX: 0001 RCX:
> 88001046c080
> [ 307.926369] RDX: 0017 RSI: 811c173d RDI:
> 88001046c080
> [ 307.940082] RBP: 88000c1cbf00 R08: 0020 R09:
> 8800053e4ce0
> [ 307.953984] R10: 8800053e4260 R11: 8800053e4d40 R12:
>
> [ 307.968462] R13: 0003 R14: R15:
> 0083
> [ 307.982466] FS: () GS:88001c40()
> knlGS:
> [ 307.998082] CS: 0010 DS: ES: CR0: 80050033
> [ 308.009554] CR2: 7ffc7538e000 CR3: 02411004 CR4:
> 000206a0
> [ 308.023264] Call Trace:
> [ 308.028512] ? _raw_spin_unlock_irqrestore+0x45/0x4f
> [ 308.038529] ? rcu_torture_stall+0x12d/0x12d
> [ 308.047149] ? kthread+0x114/0x123
> [ 308.054115] ? kthread+0x114/0x123
> [ 308.060625] ? kthread_create_worker_on_cpu+0x5f/0x5f
> [ 308.069703] ? ret_from_fork+0x3a/0x50
> [ 308.076537] irq event stamp: 3048
> [ 308.082507] hardirqs last enabled at (3047): []
> kfree+0x125/0x136
> [ 308.097831] hardirqs last disabled at (3048): []
> trace_hardirqs_off_thunk+0x1a/0x1c
> [ 308.115656] softirqs last enabled at (56): []
> __do_softirq+0x359/0x39b
> [ 308.130979] softirqs last disabled at (49): []
> irq_exit+0x59/0x75
> [ 308.145115] ---[ end trace 3654c8b0e1b99cb1 ]---
>
>
> To reproduce:
>
> git clone https://github.com/intel/lkp-tests.git
> cd lkp-tests
> bin/lkp qemu -k job-script # job-script is attached in this
> email
>
>
>
> Thanks,
> Rong, Chen
> #
> # Automatically generated file; DO NOT EDIT.
> # Linux/x86_64 4.19.0-rc1 Kernel Configuration
> #
>
> #
> # Compiler: gcc-7 (Debian 7.3.0-16) 7.3.0
> #
> CONFIG_CC_IS_GCC=y
> CONFIG_GCC_VERSION=70300
> CONFIG_CLANG_VERSION=0
> CONFIG_CONSTRUCTORS=y
> CONFIG_IRQ_WORK=y
> CONFIG_BUILDTIME_EXTABLE_SORT=y
> CONFIG_THREAD_INFO_IN_TASK=y
>
> #
> # General setup
> #
> CONFIG_INIT_ENV_ARG_LIMIT=32
> # CONFIG_COMPILE_TEST is not set
> CONFIG_LOCALVERSION=""
> CONFIG_LOCALVERSION_AUTO=y
> CONFIG_BUILD_SALT=""
> CONFIG_HAVE_KERNEL_GZIP=y
> CONFIG_HAVE_KERNEL_BZIP2=y
> CONFIG_HAVE_KERNEL_LZMA=y
>
[LKP] [rcutorture] 894b343aa8: WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog
FYI, we noticed the following commit (built with gcc-7):
commit: 894b343aa8bec5ee732329f1db09b9f5c2794de5 ("rcutorture: Add call_rcu()
flooding forward-progress tests")
https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git
dev.2018.08.30a
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -smp 2
-m 512M
caused below changes (please refer to attached dmesg/kmsg for entire
log/backtrace):
+--+++
| | 93fd89934b
| 894b343aa8 |
+--+++
| boot_successes | 24
| 18 |
| boot_failures| 1
| 12 |
| invoked_oom-killer:gfp_mask=0x | 1
| 2 |
| Mem-Info | 1
| 2 |
| Out_of_memory:Kill_process | 1
||
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 0
| 2 |
| RIP:rcu_torture_fwd_prog | 0
| 10 |
| WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog | 0
| 9 |
+--+++
[ 307.810166] WARNING: CPU: 1 PID: 54 at kernel/rcu/rcutorture.c:1840
rcu_torture_fwd_prog+0x41f/0x542
[ 307.832010] Modules linked in:
[ 307.837737] CPU: 1 PID: 54 Comm: rcu_torture_fwd Tainted: GT
4.19.0-rc1-00151-g894b343 #1
[ 307.856076] RIP: 0010:rcu_torture_fwd_prog+0x41f/0x542
[ 307.866058] Code: b8 0e 00 eb e2 48 c7 05 c9 25 35 01 f0 fa 78 83 c6 05 92
99 67 02 00 e8 29 6c 09 00 84 c0 0f 85 af 00 00 00 49 83 fc 63 7f 02 <0f> 0b 48
8b 45 88 4f 8d 44 3d 00 4d 89 e9 48 c7 c6 a0 34 e2 81 48
[ 307.902163] RSP: 0018:88000c1cbe80 EFLAGS: 00010293
[ 307.912698] RAX: RBX: 0001 RCX: 88001046c080
[ 307.926369] RDX: 0017 RSI: 811c173d RDI: 88001046c080
[ 307.940082] RBP: 88000c1cbf00 R08: 0020 R09: 8800053e4ce0
[ 307.953984] R10: 8800053e4260 R11: 8800053e4d40 R12:
[ 307.968462] R13: 0003 R14: R15: 0083
[ 307.982466] FS: () GS:88001c40()
knlGS:
[ 307.998082] CS: 0010 DS: ES: CR0: 80050033
[ 308.009554] CR2: 7ffc7538e000 CR3: 02411004 CR4: 000206a0
[ 308.023264] Call Trace:
[ 308.028512] ? _raw_spin_unlock_irqrestore+0x45/0x4f
[ 308.038529] ? rcu_torture_stall+0x12d/0x12d
[ 308.047149] ? kthread+0x114/0x123
[ 308.054115] ? kthread+0x114/0x123
[ 308.060625] ? kthread_create_worker_on_cpu+0x5f/0x5f
[ 308.069703] ? ret_from_fork+0x3a/0x50
[ 308.076537] irq event stamp: 3048
[ 308.082507] hardirqs last enabled at (3047): []
kfree+0x125/0x136
[ 308.097831] hardirqs last disabled at (3048): []
trace_hardirqs_off_thunk+0x1a/0x1c
[ 308.115656] softirqs last enabled at (56): []
__do_softirq+0x359/0x39b
[ 308.130979] softirqs last disabled at (49): []
irq_exit+0x59/0x75
[ 308.145115] ---[ end trace 3654c8b0e1b99cb1 ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k job-script # job-script is attached in this
email
Thanks,
Rong, Chen
#
# Automatically generated file; DO NOT EDIT.
# Linux/x86_64 4.19.0-rc1 Kernel Configuration
#
#
# Compiler: gcc-7 (Debian 7.3.0-16) 7.3.0
#
CONFIG_CC_IS_GCC=y
CONFIG_GCC_VERSION=70300
CONFIG_CLANG_VERSION=0
CONFIG_CONSTRUCTORS=y
CONFIG_IRQ_WORK=y
CONFIG_BUILDTIME_EXTABLE_SORT=y
CONFIG_THREAD_INFO_IN_TASK=y
#
# General setup
#
CONFIG_INIT_ENV_ARG_LIMIT=32
# CONFIG_COMPILE_TEST is not set
CONFIG_LOCALVERSION=""
CONFIG_LOCALVERSION_AUTO=y
CONFIG_BUILD_SALT=""
CONFIG_HAVE_KERNEL_GZIP=y
CONFIG_HAVE_KERNEL_BZIP2=y
CONFIG_HAVE_KERNEL_LZMA=y
CONFIG_HAVE_KERNEL_XZ=y
CONFIG_HAVE_KERNEL_LZO=y
CONFIG_HAVE_KERNEL_LZ4=y
CONFIG_KERNEL_GZIP=y
# CONFIG_KERNEL_BZIP2 is not set
# CONFIG_KERNEL_LZMA is not set
# CONFIG_KERNEL_XZ is not set
# CONFIG_KERNEL_LZO is not set
# CONFIG_KERNEL_LZ4 is not set
CONFIG_DEFAULT_HOSTNAME="(none)"
# CONFIG_SYSVIPC is not set
# CONFIG_POSIX_MQUEUE is not set
CONFIG_CROSS_MEMORY_ATTACH=y
CONFIG_USELIB=y
# CONFIG_AUDIT is not set
CONFIG_HAVE_ARCH_AUDITSYSCALL=y
#
# IRQ subsystem
#
CONFIG_GENERIC_IRQ_PROBE=y
CONFIG_GENERIC_IRQ_SHOW=y
[LKP] [rcutorture] 894b343aa8: WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog
FYI, we noticed the following commit (built with gcc-7):
commit: 894b343aa8bec5ee732329f1db09b9f5c2794de5 ("rcutorture: Add call_rcu()
flooding forward-progress tests")
https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git
dev.2018.08.30a
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -smp 2
-m 512M
caused below changes (please refer to attached dmesg/kmsg for entire
log/backtrace):
+--+++
| | 93fd89934b
| 894b343aa8 |
+--+++
| boot_successes | 24
| 18 |
| boot_failures| 1
| 12 |
| invoked_oom-killer:gfp_mask=0x | 1
| 2 |
| Mem-Info | 1
| 2 |
| Out_of_memory:Kill_process | 1
||
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 0
| 2 |
| RIP:rcu_torture_fwd_prog | 0
| 10 |
| WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_fwd_prog | 0
| 9 |
+--+++
[ 307.810166] WARNING: CPU: 1 PID: 54 at kernel/rcu/rcutorture.c:1840
rcu_torture_fwd_prog+0x41f/0x542
[ 307.832010] Modules linked in:
[ 307.837737] CPU: 1 PID: 54 Comm: rcu_torture_fwd Tainted: GT
4.19.0-rc1-00151-g894b343 #1
[ 307.856076] RIP: 0010:rcu_torture_fwd_prog+0x41f/0x542
[ 307.866058] Code: b8 0e 00 eb e2 48 c7 05 c9 25 35 01 f0 fa 78 83 c6 05 92
99 67 02 00 e8 29 6c 09 00 84 c0 0f 85 af 00 00 00 49 83 fc 63 7f 02 <0f> 0b 48
8b 45 88 4f 8d 44 3d 00 4d 89 e9 48 c7 c6 a0 34 e2 81 48
[ 307.902163] RSP: 0018:88000c1cbe80 EFLAGS: 00010293
[ 307.912698] RAX: RBX: 0001 RCX: 88001046c080
[ 307.926369] RDX: 0017 RSI: 811c173d RDI: 88001046c080
[ 307.940082] RBP: 88000c1cbf00 R08: 0020 R09: 8800053e4ce0
[ 307.953984] R10: 8800053e4260 R11: 8800053e4d40 R12:
[ 307.968462] R13: 0003 R14: R15: 0083
[ 307.982466] FS: () GS:88001c40()
knlGS:
[ 307.998082] CS: 0010 DS: ES: CR0: 80050033
[ 308.009554] CR2: 7ffc7538e000 CR3: 02411004 CR4: 000206a0
[ 308.023264] Call Trace:
[ 308.028512] ? _raw_spin_unlock_irqrestore+0x45/0x4f
[ 308.038529] ? rcu_torture_stall+0x12d/0x12d
[ 308.047149] ? kthread+0x114/0x123
[ 308.054115] ? kthread+0x114/0x123
[ 308.060625] ? kthread_create_worker_on_cpu+0x5f/0x5f
[ 308.069703] ? ret_from_fork+0x3a/0x50
[ 308.076537] irq event stamp: 3048
[ 308.082507] hardirqs last enabled at (3047): []
kfree+0x125/0x136
[ 308.097831] hardirqs last disabled at (3048): []
trace_hardirqs_off_thunk+0x1a/0x1c
[ 308.115656] softirqs last enabled at (56): []
__do_softirq+0x359/0x39b
[ 308.130979] softirqs last disabled at (49): []
irq_exit+0x59/0x75
[ 308.145115] ---[ end trace 3654c8b0e1b99cb1 ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k job-script # job-script is attached in this
email
Thanks,
Rong, Chen
#
# Automatically generated file; DO NOT EDIT.
# Linux/x86_64 4.19.0-rc1 Kernel Configuration
#
#
# Compiler: gcc-7 (Debian 7.3.0-16) 7.3.0
#
CONFIG_CC_IS_GCC=y
CONFIG_GCC_VERSION=70300
CONFIG_CLANG_VERSION=0
CONFIG_CONSTRUCTORS=y
CONFIG_IRQ_WORK=y
CONFIG_BUILDTIME_EXTABLE_SORT=y
CONFIG_THREAD_INFO_IN_TASK=y
#
# General setup
#
CONFIG_INIT_ENV_ARG_LIMIT=32
# CONFIG_COMPILE_TEST is not set
CONFIG_LOCALVERSION=""
CONFIG_LOCALVERSION_AUTO=y
CONFIG_BUILD_SALT=""
CONFIG_HAVE_KERNEL_GZIP=y
CONFIG_HAVE_KERNEL_BZIP2=y
CONFIG_HAVE_KERNEL_LZMA=y
CONFIG_HAVE_KERNEL_XZ=y
CONFIG_HAVE_KERNEL_LZO=y
CONFIG_HAVE_KERNEL_LZ4=y
CONFIG_KERNEL_GZIP=y
# CONFIG_KERNEL_BZIP2 is not set
# CONFIG_KERNEL_LZMA is not set
# CONFIG_KERNEL_XZ is not set
# CONFIG_KERNEL_LZO is not set
# CONFIG_KERNEL_LZ4 is not set
CONFIG_DEFAULT_HOSTNAME="(none)"
# CONFIG_SYSVIPC is not set
# CONFIG_POSIX_MQUEUE is not set
CONFIG_CROSS_MEMORY_ATTACH=y
CONFIG_USELIB=y
# CONFIG_AUDIT is not set
CONFIG_HAVE_ARCH_AUDITSYSCALL=y
#
# IRQ subsystem
#
CONFIG_GENERIC_IRQ_PROBE=y
CONFIG_GENERIC_IRQ_SHOW=y
