[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]
Message-ID: <20200809043537.GB30891@shao2-debian>
Date: Sun, 9 Aug 2020 12:35:37 +0800
From: kernel test robot <lkp@...el.com>
To: Jim Cromie <jim.cromie@...il.com>
Cc: jbaron@...mai.com, linux-kernel@...r.kernel.org,
Jim Cromie <jim.cromie@...il.com>,
Sumit Semwal <sumit.semwal@...aro.org>,
linux-media@...r.kernel.org, dri-devel@...ts.freedesktop.org,
linaro-mm-sig@...ts.linaro.org, 0day robot <lkp@...el.com>,
lkp@...ts.01.org
Subject: [dyndbg] 4397a3e7bf:
BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: 4397a3e7bf020ef040be371dcc178db258b928b4 ("[PATCH 7/7] dyndbg: enable 'cache' of active pr_debug callsites")
url: https://github.com/0day-ci/linux/commits/Jim-Cromie/dyndbg-WIP-diet-plan/20200808-041343
base: https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git 86cfccb66937dd6cbf26ed619958b9e587e6a115
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | cec7683cb5 | 4397a3e7bf |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 12 | 0 |
| boot_failures | 12 | 20 |
| WARNING:at_kernel/bpf/verifier.c:#replace_map_fd_with_map_ptr | 12 | |
| RIP:replace_map_fd_with_map_ptr | 12 | |
| WARNING:at_lib/refcount.c:#refcount_warn_saturate | 12 | |
| RIP:refcount_warn_saturate | 12 | |
| WARNING:possible_recursive_locking_detected | 1 | |
| RIP:lock_release | 1 | |
| BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 19 |
| BUG:scheduling_while_atomic | 0 | 7 |
| Kernel_panic-not_syncing:No_working_init_found | 0 | 8 |
| BUG:sleeping_function_called_from_invalid_context_at_mm/memory.c | 0 | 11 |
| BUG:kernel_hang_in_boot_stage | 0 | 1 |
+------------------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp@...el.com>
[ 29.668135] BUG: sleeping function called from invalid context at mm/slab.h:567
[ 29.668942] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1, name: swapper/0
[ 29.669798] 4 locks held by swapper/0/1:
[ 29.670263] #0: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.671178] #1: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.672091] #2: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.672988] #3: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.673892] Preemption disabled at:
[ 29.673894] [<ffffffff97bcb678>] zs_map_object+0x38/0x330
[ 29.674897] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 5.8.0-10185-g4397a3e7bf020 #1
[ 29.675728] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 29.676622] Call Trace:
[ 29.676956] dump_stack+0x96/0xd0
[ 29.677358] ___might_sleep.cold+0xff/0x115
[ 29.677831] ? getname_kernel+0x25/0x110
[ 29.678281] kmem_cache_alloc+0x212/0x2d0
[ 29.678739] getname_kernel+0x25/0x110
[ 29.679175] ? rest_init+0x23e/0x23e
[ 29.679598] kernel_execve+0x19/0x1c0
[ 29.680034] kernel_init+0x6e/0x112
[ 29.680450] ret_from_fork+0x22/0x30
[ 29.680905]
[ 29.681171] =============================
[ 29.681629] [ BUG: Invalid wait context ]
[ 29.682087] 5.8.0-10185-g4397a3e7bf020 #1 Tainted: G W
[ 29.682741] -----------------------------
[ 29.683200] swapper/0/1 is trying to lock:
[ 29.683663] ffff8fe003808128 (&mm->mmap_lock#2){++++}-{3:3}, at: alloc_bprm+0x126/0x2b0
[ 29.684537] other info that might help us debug this:
[ 29.685079] context-{4:4}
[ 29.685426] 4 locks held by swapper/0/1:
[ 29.685876] #0: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.686767] #1: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.687659] #2: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.688557] #3: ffff8fe0d5065040 (&zspage->lock){.+.+}-{2:2}, at: zs_map_object+0x82/0x330
[ 29.689449] stack backtrace:
[ 29.689817] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W 5.8.0-10185-g4397a3e7bf020 #1
[ 29.690762] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 29.691651] Call Trace:
[ 29.691990] dump_stack+0x96/0xd0
[ 29.692396] __lock_acquire.cold+0xc4/0x1a5
[ 29.692870] lock_acquire+0xab/0x390
[ 29.693295] ? alloc_bprm+0x126/0x2b0
[ 29.693728] down_write_killable+0x3d/0xa0
[ 29.695267] ? alloc_bprm+0x126/0x2b0
[ 29.695701] alloc_bprm+0x126/0x2b0
[ 29.696125] ? rest_init+0x23e/0x23e
[ 29.696551] kernel_execve+0x38/0x1c0
[ 29.696983] kernel_init+0x6e/0x112
[ 29.697401] ret_from_fork+0x22/0x30
[ 29.697888] BUG: scheduling while atomic: swapper/0/1/0x00000011
[ 29.698541] INFO: lockdep is turned off.
[ 29.699000] Modules linked in:
[ 29.699396] Preemption disabled at:
[ 29.699398] [<ffffffff97bcb678>] zs_map_object+0x38/0x330
[ 29.700414] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W 5.8.0-10185-g4397a3e7bf020 #1
[ 29.701373] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[ 29.702268] Call Trace:
[ 29.702604] dump_stack+0x96/0xd0
[ 29.703012] __schedule_bug.cold+0xa1/0xb2
[ 29.703482] __schedule+0x8e3/0xa90
[ 29.703906] ? try_to_wake_up+0x1d0/0x860
[ 29.704371] ? wait_for_completion+0x81/0x110
[ 29.704861] schedule+0x53/0x100
[ 29.705259] schedule_timeout+0x205/0x2b0
[ 29.705722] ? try_to_wake_up+0x7d/0x860
[ 29.706176] ? _raw_spin_unlock_irq+0x24/0x50
[ 29.706666] ? wait_for_completion+0x81/0x110
[ 29.707155] ? wait_for_completion+0x81/0x110
[ 29.707646] wait_for_completion+0xab/0x110
[ 29.708130] stop_one_cpu+0x87/0xb0
[ 29.708551] ? set_cpus_allowed_ptr+0x20/0x20
[ 29.709044] ? _raw_spin_unlock_irqrestore+0x41/0x70
[ 29.709581] sched_exec+0x98/0xd0
[ 29.709989] bprm_execve+0x1d7/0x3a0
[ 29.710416] ? rest_init+0x23e/0x23e
[ 29.710844] kernel_execve+0x135/0x1c0
[ 29.711284] kernel_init+0x6e/0x112
[ 29.711703] ret_from_fork+0x22/0x30
[ 29.712298] Failed to execute /init (error -14)
[ 29.713523] Run /sbin/init as init process
[ 29.714612] with arguments:
[ 29.715484] /sbin/init
[ 29.716328] with environment:
[ 29.717235] HOME=/
[ 29.717983] TERM=linux
[ 29.718801] user=lkp
[ 29.719587] job=/lkp/jobs/scheduled/vm-snb-151/boot-1-aliyun-x86_64-20190626.cgz-4397a3e7bf020ef040be371dcc178db258b928b4-20200808-11882-w8z2ep-10.yaml
[ 29.721551] ARCH=x86_64
[ 29.721986] kconfig=x86_64-rhel-7.6-kselftests
[ 29.722623] branch=linux-review/Jim-Cromie/dyndbg-WIP-diet-plan/20200808-041343
[ 29.723634] commit=4397a3e7bf020ef040be371dcc178db258b928b4
[ 29.724395] BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6-kselftests/gcc-9/4397a3e7bf020ef040be371dcc178db258b928b4/vmlinuz-5.8.0-10185-g4397a3e7bf020
[ 29.725943] max_uptime=600
[ 29.726406] RESULT_ROOT=/result/boot/1/vm-snb/aliyun-x86_64-20190626.cgz/x86_64-rhel-7.6-kselftests/gcc-9/4397a3e7bf020ef040be371dcc178db258b928b4/8
[ 29.728009] LKP_SERVER=inn
[ 29.728475] softlockup_panic=1
[ 29.728969] prompt_ramdisk=0
[ 29.729449] vga=normal
[ 29.729967] Starting init: /sbin/init exists but couldn't execute it (error -14)
[ 29.730967] Run /etc/init as init process
[ 29.731526] with arguments:
[ 29.731986] /etc/init
[ 29.732410] with environment:
[ 29.732880] HOME=/
[ 29.733277] TERM=linux
[ 29.733711] user=lkp
[ 29.734125] job=/lkp/jobs/scheduled/vm-snb-151/boot-1-aliyun-x86_64-20190626.cgz-4397a3e7bf020ef040be371dcc178db258b928b4-20200808-11882-w8z2ep-10.yaml
[ 29.735762] ARCH=x86_64
[ 29.736211] kconfig=x86_64-rhel-7.6-kselftests
[ 29.736844] branch=linux-review/Jim-Cromie/dyndbg-WIP-diet-plan/20200808-041343
[ 29.737860] commit=4397a3e7bf020ef040be371dcc178db258b928b4
[ 29.738609] BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6-kselftests/gcc-9/4397a3e7bf020ef040be371dcc178db258b928b4/vmlinuz-5.8.0-10185-g4397a3e7bf020
[ 29.740172] max_uptime=600
[ 29.740634] RESULT_ROOT=/result/boot/1/vm-snb/aliyun-x86_64-20190626.cgz/x86_64-rhel-7.6-kselftests/gcc-9/4397a3e7bf020ef040be371dcc178db258b928b4/8
[ 29.742238] LKP_SERVER=inn
[ 29.742699] softlockup_panic=1
[ 29.743202] prompt_ramdisk=0
[ 29.743680] vga=normal
[ 29.744156] Run /bin/init as init process
[ 29.744719] with arguments:
[ 29.745179] /bin/init
[ 29.745599] with environment:
[ 29.746074] HOME=/
[ 29.746468] TERM=linux
[ 29.746894] user=lkp
[ 29.747309] job=/lkp/jobs/scheduled/vm-snb-151/boot-1-aliyun-x86_64-20190626.cgz-4397a3e7bf020ef040be371dcc178db258b928b4-20200808-11882-w8z2ep-10.yaml
[ 29.748964] ARCH=x86_64
[ 29.749406] kconfig=x86_64-rhel-7.6-kselftests
[ 29.750047] branch=linux-review/Jim-Cromie/dyndbg-WIP-diet-plan/20200808-041343
[ 29.751067] commit=4397a3e7bf020ef040be371dcc178db258b928b4
[ 29.751818] BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6-kselftests/gcc-9/4397a3e7bf020ef040be371dcc178db258b928b4/vmlinuz-5.8.0-10185-g4397a3e7bf020
[ 29.753381] max_uptime=600
[ 29.753845] RESULT_ROOT=/result/boot/1/vm-snb/aliyun-x86_64-20190626.cgz/x86_64-rhel-7.6-kselftests/gcc-9/4397a3e7bf020ef040be371dcc178db258b928b4/8
[ 29.755455] LKP_SERVER=inn
[ 29.755923] softlockup_panic=1
[ 29.756425] prompt_ramdisk=0
[ 29.756905] vga=normal
[ 29.757375] Run /bin/sh as init process
[ 29.757921] with arguments:
[ 29.758380] /bin/sh
[ 29.758782] with environment:
[ 29.759256] HOME=/
[ 29.759650] TERM=linux
[ 29.760089] user=lkp
[ 29.760501] job=/lkp/jobs/scheduled/vm-snb-151/boot-1-aliyun-x86_64-20190626.cgz-4397a3e7bf020ef040be371dcc178db258b928b4-20200808-11882-w8z2ep-10.yaml
[ 29.762137] ARCH=x86_64
[ 29.762574] kconfig=x86_64-rhel-7.6-kselftests
[ 29.763213] branch=linux-review/Jim-Cromie/dyndbg-WIP-diet-plan/20200808-041343
[ 29.764255] commit=4397a3e7bf020ef040be371dcc178db258b928b4
[ 29.765006] BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6-kselftests/gcc-9/4397a3e7bf020ef040be371dcc178db258b928b4/vmlinuz-5.8.0-10185-g4397a3e7bf020
[ 29.766567] max_uptime=600
To reproduce:
# build kernel
cd linux
cp config-5.8.0-10185-g4397a3e7bf020 .config
make HOSTCC=gcc-9 CC=gcc-9 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
lkp
View attachment "config-5.8.0-10185-g4397a3e7bf020" of type "text/plain" (209644 bytes)
View attachment "job-script" of type "text/plain" (4682 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (14204 bytes)
Powered by blists - more mailing lists