lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<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

Powered by Openwall GNU/*/Linux Powered by OpenVZ