| 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
| ||
|
Date: Sun, 22 Oct 2017 10:18:54 +0800
From: kernel test robot <fengguang.wu@...el.com>
To: Chris Metcalf <cmetcalf@...lanox.com>
Cc: LKML <linux-kernel@...r.kernel.org>,
Chris Metcalf <cmetcalf@...hip.com>, lkp@...org
Subject: e61bad9bed:
BUG:sleeping_function_called_from_invalid_context_at_kernel/workqueue.c
FYI, we noticed the following commit (built with gcc-6):
commit: e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e ("Make quiet_vmstat() work a little harder")
https://git.kernel.org/cgit/linux/kernel/git/cmetcalf/linux-tile.git dataplane
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-------------------------------------------------------------------------+-----------+------------+
| | v4.14-rc4 | e61bad9bed |
+-------------------------------------------------------------------------+-----------+------------+
| boot_successes | 2967 | 0 |
| boot_failures | 431 | 8 |
| BUG:kernel_hang_in_test_stage | 429 | |
| BUG:kernel_reboot-without-warning_in_test_stage | 2 | |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/workqueue.c | 0 | 8 |
+-------------------------------------------------------------------------+-----------+------------+
[ 10.883408] BUG: sleeping function called from invalid context at kernel/workqueue.c:2821
[ 10.884804] in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/1
[ 10.885747] no locks held by swapper/1/0.
[ 10.886287] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.14.0-rc4-00001-ge61bad9b #15
[ 10.887330] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 10.888500] Call Trace:
[ 10.888836] dump_stack+0x61/0x7e
[ 10.889303] ___might_sleep+0x118/0x127
[ 10.889838] __might_sleep+0x68/0x6f
[ 10.890313] flush_work+0x6d/0x266
[ 10.890804] ? del_timer+0x3e/0x49
[ 10.891268] ? del_timer+0x3e/0x49
[ 10.891761] __cancel_work_timer+0xf3/0x152
[ 10.893093] ? __schedule+0x618/0x6c4
[ 10.893672] cancel_delayed_work_sync+0xe/0x10
[ 10.894245] quiet_vmstat+0x33/0x4a
[ 10.894739] do_idle+0x26/0x144
[ 10.895159] cpu_startup_entry+0x1a/0x1c
[ 10.895735] start_secondary+0x113/0x116
[ 10.896257] secondary_startup_64+0xa5/0xa5
[ 10.907269] systemd-cryptse (136) used greatest stack depth: 14080 bytes left
[ 10.913135] systemd-debug-g (135) used greatest stack depth: 13848 bytes left
[ 10.919696] systemd-sysv-ge (137) used greatest stack depth: 13696 bytes left
Mounting Huge Pages File System...
Starting Load Kernel Modules...
Starting Create Static Device Nodes in /dev...
Mounting POSIX Message Queue File System...
Starting Remount Root and Kernel File Systems...
Starting Journal Service...
Mounting RPC Pipe File System...
Mounting Debug File System...
Starting udev Kernel Device Manager...
Starting Apply Kernel Variables...
Mounting Configuration File System...
Starting Load/Save Random Seed...
Starting Preprocess NFS configuration...
Starting udev Coldplug all Devices...
Starting Flush Journal to Persistent Storage...
Starting Create Volatile Files and Directories...
Starting Network Time Synchronization...
Starting Update UTMP about System Boot/Shutdown...
Starting RPC bind portmap service...
Starting LKP bootstrap...
Starting /etc/rc.local Compatibility...
Starting OpenBSD Secure Shell server...
[ 11.606777] rc.local[212]: mkdir: cannot create directory '/var/lock/lkp-bootstrap.lock': File exists
Starting Login Service...
Starting Permit User Sessions...
LKP: HOSTNAME vm-lkp-nex04-4G-1, MAC 52:54:00:12:34:56, kernel 4.14.0-rc4-00001-ge61bad9b 15, serial console /dev/ttyS0
[ 11.788754] Kernel tests: Boot OK!
[ 11.788761]
[ 11.823472] install debs round one: dpkg -i --force-depends /opt/deb/debconf_1.5.63_all.deb
[ 11.823477]
[ 11.826304] /opt/deb/libtext-charwidth-perl_0.04-7+b7_amd64.deb
[ 11.826308]
[ 11.828464] /opt/deb/libtext-iconv-perl_1.7-5+b6_amd64.deb
[ 11.828468]
[ 11.830205] /opt/deb/perl-base_5.26.0-5_amd64.deb
[ 11.830208]
[ 11.836176] /opt/deb/liblocale-gettext-perl_1.07-3+b3_amd64.deb
[ 11.836181]
[ 11.838115] /opt/deb/perl-modules-5.26_5.26.0-5_all.deb
[ 11.838119]
[ 11.839852] /opt/deb/libperl5.26_5.26.0-5_amd64.deb
[ 11.839856]
[ 11.843854] /opt/deb/perl_5.26.0-5_amd64.deb
[ 11.843858]
[ 11.845727] /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb
[ 11.845731]
[ 11.847374] /opt/deb/libssl1.1_1.1.0f-3_amd64.deb
[ 11.847378]
[ 11.850326] /opt/deb/openssl_1.1.0f-3_amd64.deb
[ 11.850331]
[ 11.856128] /opt/deb/ca-certificates_20161130+nmu1_all.deb
[ 11.856133]
[ 11.858632] (Reading database ... 2202 files and directories currently installed.)
[ 11.858636]
[ 11.869194] Preparing to unpack .../opt/deb/debconf_1.5.63_all.deb ...
[ 11.869198]
[ 11.885463] Unpacking debconf (1.5.63) over (1.5.59) ...
[ 11.885467]
[ 12.023350] BUG: sleeping function called from invalid context at kernel/workqueue.c:2821
[ 12.032153] in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/1
[ 12.033050] no locks held by swapper/1/0.
[ 12.033579] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 4.14.0-rc4-00001-ge61bad9b #15
[ 12.034708] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 12.035768] Call Trace:
[ 12.036113] dump_stack+0x61/0x7e
[ 12.036553] ___might_sleep+0x118/0x127
[ 12.037058] __might_sleep+0x68/0x6f
[ 12.037534] flush_work+0x6d/0x266
[ 12.037984] ? del_timer+0x3e/0x49
[ 12.038437] ? del_timer+0x3e/0x49
[ 12.038896] __cancel_work_timer+0xf3/0x152
[ 12.039450] ? __schedule+0x618/0x6c4
[ 12.039946] cancel_delayed_work_sync+0xe/0x10
[ 12.040514] quiet_vmstat+0x33/0x4a
[ 12.040985] do_idle+0x26/0x144
[ 12.041402] cpu_startup_entry+0x1a/0x1c
[ 12.041899] start_secondary+0x113/0x116
[ 12.042421] secondary_startup_64+0xa5/0xa5
[ 12.052480] Preparing to unpack .../libtext-charwidth-perl_0.04-7+b7_amd64.deb ...
[ 12.052487]
[ 12.055218] Unpacking libtext-charwidth-perl (0.04-7+b7) over (0.04-7+b4) ...
[ 12.055223]
[ 12.100863] Preparing to unpack .../libtext-iconv-perl_1.7-5+b6_amd64.deb ...
[ 12.100868]
[ 12.103348] Unpacking libtext-iconv-perl (1.7-5+b6) over (1.7-5+b3) ...
[ 12.103352]
[ 12.136238] Preparing to unpack .../perl-base_5.26.0-5_amd64.deb ...
[ 12.136245]
[ 12.138959] Unpacking perl-base (5.26.0-5) over (5.22.2-3) ...
[ 12.138964]
[ 12.690322] Preparing to unpack .../liblocale-gettext-perl_1.07-3+b3_amd64.deb ...
[ 12.690330]
[ 12.693469] Unpacking liblocale-gettext-perl (1.07-3+b3) over (1.07-3) ...
[ 12.693473]
[ 12.725747] Selecting previously unselected package perl-modules-5.26.
[ 12.725752]
[ 12.728374] Preparing to unpack .../perl-modules-5.26_5.26.0-5_all.deb ...
[ 12.728378]
[ 12.730509] Unpacking perl-modules-5.26 (5.26.0-5) ...
[ 12.730513]
[ 13.417619] Selecting previously unselected package libperl5.26:amd64.
[ 13.417627]
[ 13.420942] Preparing to unpack .../libperl5.26_5.26.0-5_amd64.deb ...
[ 13.420946]
[ 13.422941] Unpacking libperl5.26:amd64 (5.26.0-5) ...
[ 13.422945]
[ 13.868141] BUG: sleeping function called from invalid context at kernel/workqueue.c:2821
[ 13.871112] in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/0
[ 13.872079] no locks held by swapper/0/0.
[ 13.872660] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 4.14.0-rc4-00001-ge61bad9b #15
[ 13.873864] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 13.874968] Call Trace:
[ 13.875331] dump_stack+0x61/0x7e
[ 13.875783] ___might_sleep+0x118/0x127
[ 13.876286] __might_sleep+0x68/0x6f
[ 13.876760] flush_work+0x6d/0x266
[ 13.877215] ? del_timer+0x3e/0x49
[ 13.877725] ? del_timer+0x3e/0x49
[ 13.878183] __cancel_work_timer+0xf3/0x152
[ 13.878785] ? __schedule+0x618/0x6c4
[ 13.879253] cancel_delayed_work_sync+0xe/0x10
[ 13.879859] quiet_vmstat+0x33/0x4a
[ 13.880453] do_idle+0x26/0x144
[ 13.880867] cpu_startup_entry+0x1a/0x1c
[ 13.881420] rest_init+0x12f/0x135
[ 13.881909] start_kernel+0x45d/0x46a
[ 13.882400] x86_64_start_reservations+0x24/0x26
[ 13.882993] x86_64_start_kernel+0x6a/0x6d
[ 13.883706] secondary_startup_64+0xa5/0xa5
[ 14.036506] Selecting previously unselected package perl.
[ 14.036514]
[ 14.041173] Preparing to unpack .../deb/perl_5.26.0-5_amd64.deb ...
[ 14.041178]
[ 14.044991] Unpacking perl (5.26.0-5) ...
[ 14.044995]
[ 14.091385] Preparing to unpack .../gawk_1%3a4.1.4+dfsg-1_amd64.deb ...
[ 14.091389]
[ 14.093974] Unpacking gawk (1:4.1.4+dfsg-1) over (1:4.1.1+dfsg-1) ...
[ 14.093978]
[ 14.217748] Selecting previously unselected package libssl1.1:amd64.
[ 14.217754]
[ 14.220187] Preparing to unpack .../libssl1.1_1.1.0f-3_amd64.deb ...
[ 14.220191]
[ 14.223832] Unpacking libssl1.1:amd64 (1.1.0f-3) ...
[ 14.223836]
[ 14.372413] Selecting previously unselected package openssl.
[ 14.372420]
[ 14.374778] Preparing to unpack .../deb/openssl_1.1.0f-3_amd64.deb ...
[ 14.374786]
[ 14.376762] Unpacking openssl (1.1.0f-3) ...
[ 14.376766]
[ 14.501981] Selecting previously unselected package ca-certificates.
[ 14.501988]
[ 14.504873] Preparing to unpack .../ca-certificates_20161130+nmu1_all.deb ...
[ 14.504878]
[ 14.507031] Unpacking ca-certificates (20161130+nmu1) ...
[ 14.507035]
[ 14.602571] Setting up perl-base (5.26.0-5) ...
[ 14.602575]
[ 14.604610] Setting up liblocale-gettext-perl (1.07-3+b3) ...
[ 14.604615]
[ 14.606561] Setting up perl-modules-5.26 (5.26.0-5) ...
[ 14.606565]
[ 14.609580] Setting up debconf (1.5.63) ...
[ 14.609585]
[ 14.892401] BUG: sleeping function called from invalid context at kernel/workqueue.c:2821
[ 14.893760] in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/0
[ 14.894712] no locks held by swapper/0/0.
[ 14.895339] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 4.14.0-rc4-00001-ge61bad9b #15
[ 14.896471] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 14.897640] Call Trace:
[ 14.898027] dump_stack+0x61/0x7e
[ 14.898503] ___might_sleep+0x118/0x127
[ 14.899045] __might_sleep+0x68/0x6f
[ 14.899521] flush_work+0x6d/0x266
[ 14.900013] ? del_timer+0x3e/0x49
[ 14.900478] ? del_timer+0x3e/0x49
[ 14.900947] __cancel_work_timer+0xf3/0x152
[ 14.901661] ? __schedule+0x618/0x6c4
[ 14.902140] cancel_delayed_work_sync+0xe/0x10
[ 14.902708] quiet_vmstat+0x33/0x4a
[ 14.903171] do_idle+0x26/0x144
[ 14.903638] cpu_startup_entry+0x1a/0x1c
[ 14.904148] rest_init+0x12f/0x135
[ 14.904643] start_kernel+0x45d/0x46a
[ 14.905202] x86_64_start_reservations+0x24/0x26
[ 14.905829] x86_64_start_kernel+0x6a/0x6d
[ 14.906360] secondary_startup_64+0xa5/0xa5
[ 14.964103] Setting up libtext-charwidth-perl (0.04-7+b7) ...
[ 14.964110]
[ 14.966189] Setting up libtext-iconv-perl (1.7-5+b6) ...
[ 14.966193]
[ 14.968318] Setting up libssl1.1:amd64 (1.1.0f-3) ...
[ 14.968323]
[ 15.189106] Setting up openssl (1.1.0f-3) ...
[ 15.189113]
[ 15.191906] Setting up ca-certificates (20161130+nmu1) ...
[ 15.191912]
[ 16.828935] BUG: sleeping function called from invalid context at kernel/workqueue.c:2821
[ 16.830311] in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/1
[ 16.831163] no locks held by swapper/1/0.
[ 16.831681] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 4.14.0-rc4-00001-ge61bad9b #15
[ 16.832932] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 16.834012] Call Trace:
[ 16.834339] dump_stack+0x61/0x7e
[ 16.834768] ___might_sleep+0x118/0x127
[ 16.835258] __might_sleep+0x68/0x6f
[ 16.835753] flush_work+0x6d/0x266
[ 16.836183] ? del_timer+0x3e/0x49
[ 16.836739] ? del_timer+0x3e/0x49
[ 16.837182] __cancel_work_timer+0xf3/0x152
[ 16.837697] ? __schedule+0x618/0x6c4
[ 16.838154] cancel_delayed_work_sync+0xe/0x10
[ 16.838773] quiet_vmstat+0x33/0x4a
[ 16.839261] do_idle+0x26/0x144
[ 16.839651] cpu_startup_entry+0x1a/0x1c
[ 16.840188] start_secondary+0x113/0x116
[ 16.840698] secondary_startup_64+0xa5/0xa5
[ 19.636576] Setting up gawk (1:4.1.4+dfsg-1) ...
[ 19.636584]
[ 19.641178] Setting up libperl5.26:amd64 (5.26.0-5) ...
[ 19.641183]
[ 19.643025] Setting up perl (5.26.0-5) ...
[ 19.643029]
[ 19.648907] update-alternatives: using /usr/bin/prename to provide /usr/bin/rename (rename) in auto mode
[ 19.648913]
[ 19.657675] Processing triggers for libc-bin (2.23-5) ...
[ 19.657680]
[ 19.660324] Processing triggers for ca-certificates (20161130+nmu1) ...
[ 19.660328]
[ 19.666627] Updating certificates in /etc/ssl/certs...
[ 19.666631]
[ 19.837617] BUG: sleeping function called from invalid context at kernel/workqueue.c:2821
[ 19.839011] in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/0
[ 19.840074] no locks held by swapper/0/0.
[ 19.840657] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 4.14.0-rc4-00001-ge61bad9b #15
[ 19.841938] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 19.843091] Call Trace:
[ 19.843459] dump_stack+0x61/0x7e
[ 19.843933] ___might_sleep+0x118/0x127
[ 19.844495] __might_sleep+0x68/0x6f
[ 19.845038] flush_work+0x6d/0x266
[ 19.845537] ? del_timer+0x3e/0x49
[ 19.846018] ? del_timer+0x3e/0x49
[ 19.846564] __cancel_work_timer+0xf3/0x152
[ 19.847152] ? __schedule+0x618/0x6c4
[ 19.847683] cancel_delayed_work_sync+0xe/0x10
[ 19.848315] quiet_vmstat+0x33/0x4a
[ 19.848840] do_idle+0x26/0x144
[ 19.849294] cpu_startup_entry+0x1a/0x1c
[ 19.849848] rest_init+0x12f/0x135
[ 19.850339] start_kernel+0x45d/0x46a
[ 19.850859] x86_64_start_reservations+0x24/0x26
[ 19.851517] x86_64_start_kernel+0x6a/0x6d
[ 19.852096] secondary_startup_64+0xa5/0xa5
[ 20.882157] 0 added, 0 removed; done.
[ 20.882165]
[ 20.884416] Running hooks in /etc/ca-certificates/update.d...
[ 20.884420]
[ 20.887944] done.
[ 20.887950]
[ 20.939406] /lkp/lkp/src/bin/run-lkp
[ 20.939411]
[ 20.967507] RESULT_ROOT=/result/boot/1/vm-lkp-nex04-4G/debian-x86_64-2016-08-31.cgz/x86_64-acpi-redef/gcc-6/e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e/0
[ 20.967512]
[ 20.977965] job=/lkp/scheduled/vm-lkp-nex04-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e-20171022-22807-kyf8hu-0.yaml
[ 20.977972]
[ 21.042916] mount.nfs (3877) used greatest stack depth: 11432 bytes left
[ 21.081186] run-job /lkp/scheduled/vm-lkp-nex04-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e-20171022-22807-kyf8hu-0.yaml
[ 21.081193]
[ 21.124191] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-nex04-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e-20171022-22807-kyf8hu-0.yaml&job_state=running -o /dev/null
[ 21.124197]
Elapsed time: 20
initrds=(
/osimage/debian/debian-x86_64-2016-08-31.cgz
/lkp/scheduled/vm-lkp-nex04-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e-20171022-22807-kyf8hu-0.cgz
/lkp/lkp/lkp-x86_64.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-08-01.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz
)
cat "${initrds[@]}" > /fs/sdb1/initrd-vm-lkp-nex04-4G-1
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu host
-kernel /pkg/linux/x86_64-acpi-redef/gcc-6/e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e/vmlinuz-4.14.0-rc4-00001-ge61bad9b
-initrd /fs/sdb1/initrd-vm-lkp-nex04-4G-1
-m 4096
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0,hostfwd=tcp::23230-:22
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-drive file=/fs/sdb1/disk0-vm-lkp-nex04-4G-1,media=disk,if=virtio
-drive file=/fs/sdb1/disk1-vm-lkp-nex04-4G-1,media=disk,if=virtio
-pidfile /dev/shm/kboot/pid-vm-lkp-nex04-4G-1
-serial file:/dev/shm/kboot/vm-lkp-nex04-4G-1/serial
-serial file:/dev/shm/kboot/vm-lkp-nex04-4G-1/kmsg
-daemonize
-display none
-monitor null
)
append=(
ip=::::vm-lkp-nex04-4G-1::dhcp
root=/dev/ram0
user=lkp
job=/lkp/scheduled/vm-lkp-nex04-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e-20171022-22807-kyf8hu-0.yaml
ARCH=x86_64
kconfig=x86_64-acpi-redef
branch=linux-devel/devel-spot-201710220246
commit=e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e
BOOT_IMAGE=/pkg/linux/x86_64-acpi-redef/gcc-6/e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e/vmlinuz-4.14.0-rc4-00001-ge61bad9b
max_uptime=600
RESULT_ROOT=/result/boot/1/vm-lkp-nex04-4G/debian-x86_64-2016-08-31.cgz/x86_64-acpi-redef/gcc-6/e61bad9bed06c9fc42b662e7d09c5dfbef2aeb3e/0
LKP_SERVER=inn
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
To reproduce:
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-4.14.0-rc4-00001-ge61bad9b" of type "text/plain" (125926 bytes)
View attachment "job-script" of type "text/plain" (4163 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (15472 bytes)
Powered by blists - more mailing lists