[lkp] [perf] bd2afa49d1: -20.7% will-it-scale.per_thread_ops
by kernel test robot
FYI, we noticed the will-it-scale.per_thread_ops -20.7% regression on
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit bd2afa49d194c6412c333e9fdd48bc5d06bb465d ("perf: Fix scaling vs. perf_event_enable()")
=========================================================================================
compiler/kconfig/rootfs/tbox_group/test/testcase:
gcc-4.9/x86_64-rhel/debian-x86_64-2015-02-07.cgz/lkp-sb03/lseek1/will-it-scale
commit:
7fce250915efca0f8f51dddee3ae89bf30d86ca5
bd2afa49d194c6412c333e9fdd48bc5d06bb465d
7fce250915efca0f bd2afa49d194c6412c333e9fdd
---------------- --------------------------
%stddev %change %stddev
\ | \
7989217 ± 0% -20.7% 6333122 ± 0% will-it-scale.per_thread_ops
0.61 ± 0% -1.3% 0.60 ± 0% will-it-scale.scalability
1762 ± 0% -4.7% 1680 ± 0% will-it-scale.time.system_time
748.10 ± 0% +11.0% 830.15 ± 0% will-it-scale.time.user_time
748.10 ± 0% +11.0% 830.15 ± 0% time.user_time
0.03 ± 40% -66.7% 0.01 ±-10000% turbostat.Pkg%pc6
1.54 ± 1% -16.4% 1.28 ± 3% perf-profile.cycles.___might_sleep.__might_sleep.mutex_lock.__fdget_pos.sys_lseek
33.95 ± 0% -15.3% 28.77 ± 0% perf-profile.cycles.__fdget_pos.sys_lseek.entry_SYSCALL_64_fastpath
14.29 ± 2% -18.4% 11.67 ± 1% perf-profile.cycles.__fget.__fget_light.__fdget_pos.sys_lseek.entry_SYSCALL_64_fastpath
14.66 ± 2% -18.1% 12.02 ± 0% perf-profile.cycles.__fget_light.__fdget_pos.sys_lseek.entry_SYSCALL_64_fastpath
3.38 ± 1% -14.1% 2.90 ± 3% perf-profile.cycles.__might_sleep.mutex_lock.__fdget_pos.sys_lseek.entry_SYSCALL_64_fastpath
12.52 ± 1% -10.9% 11.16 ± 0% perf-profile.cycles.entry_SYSCALL_64
12.72 ± 1% -17.8% 10.45 ± 1% perf-profile.cycles.entry_SYSCALL_64_after_swapgs
10.29 ± 2% -17.7% 8.47 ± 1% perf-profile.cycles.fput.entry_SYSCALL_64_fastpath
1.94 ± 5% +152.2% 4.89 ± 2% perf-profile.cycles.generic_file_llseek_size.shmem_file_llseek.sys_lseek.entry_SYSCALL_64_fastpath
0.75 ± 5% +517.3% 4.65 ± 3% perf-profile.cycles.generic_file_llseek_size.sys_lseek.entry_SYSCALL_64_fastpath
11.77 ± 2% -13.5% 10.18 ± 1% perf-profile.cycles.mutex_lock.__fdget_pos.sys_lseek.entry_SYSCALL_64_fastpath
4.59 ± 3% +180.3% 12.87 ± 4% perf-profile.cycles.shmem_file_llseek.sys_lseek.entry_SYSCALL_64_fastpath
47.83 ± 1% +12.6% 53.87 ± 0% perf-profile.cycles.sys_lseek.entry_SYSCALL_64_fastpath
35661 ± 4% +26.3% 45032 ± 18% sched_debug.cfs_rq:/.exec_clock.24
34816 ± 0% +14.1% 39725 ± 14% sched_debug.cfs_rq:/.exec_clock.25
33735 ± 4% -31.2% 23222 ± 37% sched_debug.cfs_rq:/.exec_clock.8
804888 ± 3% +25.5% 1010094 ± 15% sched_debug.cfs_rq:/.min_vruntime.24
709991 ± 3% -30.1% 496149 ± 31% sched_debug.cfs_rq:/.min_vruntime.min
4.25 ± 13% -24.5% 3.21 ± 2% sched_debug.cfs_rq:/.nr_spread_over.max
1.17 ± 8% -19.6% 0.94 ± 5% sched_debug.cfs_rq:/.nr_spread_over.stddev
-585558 ± -4% +28.1% -750273 ±-13% sched_debug.cfs_rq:/.spread0.8
-595168 ± -3% +29.4% -770251 ±-14% sched_debug.cfs_rq:/.spread0.min
561.50 ± 4% -6.6% 524.25 ± 4% sched_debug.cfs_rq:/.util_avg.12
411.50 ± 14% -13.9% 354.50 ± 1% sched_debug.cfs_rq:/.util_avg.23
173.25 ± 1% +12.8% 195.50 ± 10% sched_debug.cfs_rq:/.util_avg.26
53711 ± 4% +16.0% 62301 ± 9% sched_debug.cpu.nr_load_updates.24
10450 ± 12% -22.0% 8153 ± 12% sched_debug.cpu.nr_switches.0
14829 ± 16% +32.3% 19618 ± 18% sched_debug.cpu.nr_switches.11
7613 ± 30% -59.2% 3109 ± 11% sched_debug.cpu.nr_switches.29
33333 ± 5% +47.8% 49256 ± 22% sched_debug.cpu.nr_switches.max
8197 ± 3% +32.3% 10843 ± 12% sched_debug.cpu.nr_switches.stddev
1.00 ±187% -600.0% -5.00 ±-54% sched_debug.cpu.nr_uninterruptible.11
16912 ± 19% +203.2% 51277 ± 64% sched_debug.cpu.sched_count.10
7914 ± 31% -60.3% 3144 ± 11% sched_debug.cpu.sched_count.29
3798 ± 39% +57.9% 5998 ± 30% sched_debug.cpu.sched_count.3
4658 ± 9% +90.7% 8886 ± 67% sched_debug.cpu.sched_goidle.14
3051 ± 2% +11.2% 3394 ± 4% sched_debug.cpu.sched_goidle.avg
13064 ± 9% +62.4% 21213 ± 29% sched_debug.cpu.sched_goidle.max
3266 ± 4% +41.9% 4634 ± 15% sched_debug.cpu.sched_goidle.stddev
6316 ± 13% -41.4% 3703 ± 7% sched_debug.cpu.ttwu_count.14
17502 ± 10% +82.7% 31976 ± 15% sched_debug.cpu.ttwu_count.max
4063 ± 5% +53.3% 6230 ± 11% sched_debug.cpu.ttwu_count.stddev
1733 ± 31% +49.4% 2591 ± 13% sched_debug.cpu.ttwu_local.11
899.00 ± 29% +76.7% 1588 ± 22% sched_debug.cpu.ttwu_local.27
=========================================================================================
compiler/cpufreq_governor/kconfig/rootfs/tbox_group/test/testcase:
gcc-4.9/performance/x86_64-rhel/debian-x86_64-2015-02-07.cgz/lkp-sbx04/lseek1/will-it-scale
commit:
7fce250915efca0f8f51dddee3ae89bf30d86ca5
bd2afa49d194c6412c333e9fdd48bc5d06bb465d
7fce250915efca0f bd2afa49d194c6412c333e9fdd
---------------- --------------------------
%stddev %change %stddev
\ | \
7619716 ± 0% -21.2% 6008133 ± 0% will-it-scale.per_thread_ops
0.60 ± 0% -2.6% 0.58 ± 0% will-it-scale.scalability
2982 ± 0% -4.8% 2838 ± 0% will-it-scale.time.system_time
1262 ± 0% +11.4% 1406 ± 0% will-it-scale.time.user_time
1262 ± 0% +11.4% 1406 ± 0% time.user_time
21294 ± 1% +9.4% 23298 ± 2% slabinfo.anon_vma_chain.active_objs
21294 ± 1% +9.4% 23298 ± 2% slabinfo.anon_vma_chain.num_objs
13408 ± 1% -14.4% 11482 ± 3% slabinfo.kmalloc-512.active_objs
13547 ± 1% -12.9% 11804 ± 3% slabinfo.kmalloc-512.num_objs
2.00 ± 0% +212.5% 6.25 ± 34% sched_debug.cfs_rq:/.load.27
2.50 ± 20% +1650.0% 43.75 ± 84% sched_debug.cfs_rq:/.load_avg.18
11.50 ± 4% -10.9% 10.25 ± 4% sched_debug.cfs_rq:/.load_avg.46
1133547 ± 15% -14.8% 965376 ± 0% sched_debug.cfs_rq:/.min_vruntime.57
2.00 ±-50% +12.5% 2.25 ± 57% sched_debug.cfs_rq:/.nr_spread_over.9
5.54 ± 29% -33.1% 3.71 ± 30% sched_debug.cfs_rq:/.nr_spread_over.max
0.98 ± 8% -14.8% 0.83 ± 7% sched_debug.cfs_rq:/.nr_spread_over.stddev
3.00 ± 0% -33.3% 2.00 ± 0% sched_debug.cfs_rq:/.runnable_load_avg.31
172.50 ± 1% +15.2% 198.75 ± 14% sched_debug.cfs_rq:/.util_avg.21
176.00 ± 0% +44.5% 254.25 ± 28% sched_debug.cfs_rq:/.util_avg.26
17.60 ± 5% -8.5% 16.11 ± 6% sched_debug.cpu.cpu_load[3].stddev
1792 ± 0% +27.7% 2289 ± 31% sched_debug.cpu.curr->pid.16
1793 ± 0% +25.4% 2248 ± 28% sched_debug.cpu.curr->pid.21
48688 ± 3% +6.1% 51640 ± 3% sched_debug.cpu.nr_load_updates.63
14390 ± 25% -70.2% 4282 ± 52% sched_debug.cpu.nr_switches.23
1180 ± 10% +19.8% 1414 ± 15% sched_debug.cpu.nr_switches.47
3051 ± 55% -57.1% 1309 ± 6% sched_debug.cpu.nr_switches.50
1604 ± 46% +111.0% 3385 ± 60% sched_debug.cpu.nr_switches.52
2296 ± 37% +103.1% 4664 ± 32% sched_debug.cpu.nr_switches.59
2199 ± 20% +95.1% 4292 ± 39% sched_debug.cpu.nr_switches.60
1960 ± 17% +49.9% 2939 ± 14% sched_debug.cpu.nr_switches.63
-18.75 ±-19% +62.7% -30.50 ±-21% sched_debug.cpu.nr_uninterruptible.0
-2.00 ±-86% -162.5% 1.25 ±103% sched_debug.cpu.nr_uninterruptible.19
-1.75 ±-123% -171.4% 1.25 ±103% sched_debug.cpu.nr_uninterruptible.27
1.25 ±153% +360.0% 5.75 ± 35% sched_debug.cpu.nr_uninterruptible.32
3.50 ± 84% -71.4% 1.00 ±254% sched_debug.cpu.nr_uninterruptible.46
-18.50 ±-18% +62.6% -30.08 ±-21% sched_debug.cpu.nr_uninterruptible.min
4732118 ± 0% +27.7% 6043638 ± 0% sched_debug.cpu.sched_count.0
16580 ±100% -73.9% 4329 ± 47% sched_debug.cpu.sched_count.1
14410 ± 24% -70.2% 4297 ± 52% sched_debug.cpu.sched_count.23
11351 ± 43% +159.5% 29460 ± 46% sched_debug.cpu.sched_count.27
5346 ± 55% -50.1% 2669 ± 35% sched_debug.cpu.sched_count.4
3061 ± 55% -56.9% 1320 ± 5% sched_debug.cpu.sched_count.50
1614 ± 46% +110.6% 3399 ± 60% sched_debug.cpu.sched_count.52
2035 ± 8% +59.1% 3238 ± 11% sched_debug.cpu.sched_count.57
2310 ± 37% +105.8% 4754 ± 33% sched_debug.cpu.sched_count.59
2218 ± 20% +122.3% 4931 ± 36% sched_debug.cpu.sched_count.60
1973 ± 17% +91.8% 3785 ± 37% sched_debug.cpu.sched_count.63
80242 ± 0% +26.0% 101102 ± 0% sched_debug.cpu.sched_count.avg
4732118 ± 0% +27.7% 6043638 ± 0% sched_debug.cpu.sched_count.max
586184 ± 0% +27.7% 748781 ± 0% sched_debug.cpu.sched_count.stddev
4320 ± 46% -64.8% 1520 ± 9% sched_debug.cpu.sched_goidle.20
2161 ± 73% -53.1% 1012 ± 22% sched_debug.cpu.sched_goidle.4
622.00 ± 25% -19.3% 502.25 ± 20% sched_debug.cpu.sched_goidle.44
1063 ± 38% -53.4% 496.00 ± 7% sched_debug.cpu.sched_goidle.50
480.50 ± 10% +39.0% 667.75 ± 33% sched_debug.cpu.sched_goidle.53
925.50 ± 43% +115.9% 1998 ± 39% sched_debug.cpu.sched_goidle.59
754.75 ± 6% +59.7% 1205 ± 14% sched_debug.cpu.sched_goidle.63
8103 ± 6% -11.0% 7214 ± 6% sched_debug.cpu.ttwu_count.0
1831 ± 23% +69.1% 3095 ± 10% sched_debug.cpu.ttwu_count.15
2932 ± 23% -47.1% 1550 ± 22% sched_debug.cpu.ttwu_count.18
4208 ± 27% -59.6% 1702 ± 20% sched_debug.cpu.ttwu_count.19
4212 ± 39% -69.9% 1268 ± 24% sched_debug.cpu.ttwu_count.23
5265 ± 45% -44.9% 2901 ± 16% sched_debug.cpu.ttwu_count.27
3700 ± 24% +71.1% 6331 ± 48% sched_debug.cpu.ttwu_count.28
2916 ± 90% -65.1% 1017 ± 14% sched_debug.cpu.ttwu_count.4
2347 ± 50% -62.8% 874.25 ± 77% sched_debug.cpu.ttwu_count.44
5181 ± 49% -78.6% 1107 ± 66% sched_debug.cpu.ttwu_count.46
1745 ± 28% +174.4% 4789 ± 27% sched_debug.cpu.ttwu_count.9
1182 ± 79% -63.6% 430.00 ± 13% sched_debug.cpu.ttwu_local.18
2470 ± 56% -78.6% 529.25 ± 30% sched_debug.cpu.ttwu_local.19
3327 ± 46% -86.4% 452.25 ± 33% sched_debug.cpu.ttwu_local.23
1156 ± 40% +66.5% 1925 ± 33% sched_debug.cpu.ttwu_local.24
1589 ± 9% -28.6% 1134 ± 10% sched_debug.cpu.ttwu_local.27
349.50 ± 27% -34.5% 229.00 ± 40% sched_debug.cpu.ttwu_local.33
126.50 ± 15% +43.1% 181.00 ± 18% sched_debug.cpu.ttwu_local.38
1126 ± 62% -62.2% 425.75 ± 34% sched_debug.cpu.ttwu_local.4
381.00 ± 21% +145.7% 936.00 ± 35% sched_debug.cpu.ttwu_local.59
lkp-sb03: Sandy Bridge-EP
Memory: 64G
lkp-sbx04: Sandy Bridge-EX
Memory: 64G
will-it-scale.per_thread_ops
9e+06 ++------------------------------------------------------------------+
*. .*.*.*. .*.* |
8e+06 ++*. *..*.*.*.*..*.*.*.*..*.*.*..*.*.*.*..*.* : *.*.*..*.*
7e+06 O+O O O O O O O O O O : : |
| O O O O : : |
6e+06 ++ : : |
5e+06 ++ : : |
| : : |
4e+06 ++ : : |
3e+06 ++ : : |
| : : |
2e+06 ++ : : |
1e+06 ++ : |
| : |
0 ++-------------------------------------------------------*----------+
will-it-scale.time.system_time
1800 *+*--*-*-*--*-*-*-*--*-*-*--*-*-*--*-*-*-*--*-*-*--*-*-*----*-*-*--*-*
O O O O O O O O O O O O O O O : : |
1600 ++ : : |
1400 ++ : : |
| : : |
1200 ++ : : |
1000 ++ : : |
| : : |
800 ++ : : |
600 ++ : : |
| : : |
400 ++ :: |
200 ++ : |
| : |
0 ++--------------------------------------------------------*----------+
[*] bisect-good sample
[O] bisect-bad sample
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Xiaolong.
4 years, 10 months
[lkp] [gpu/i915] 7632469f92: BUG: kernel boot crashed
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/rt/linux-rt-devel.git linux-4.4.y-rt
commit 7632469f927db4e8ebecfbc59bba16c14974a474 ("gpu/i915: don't open code these things")
+-------------------------+------------+------------+
| | 6e4ba49cad | 7632469f92 |
+-------------------------+------------+------------+
| boot_successes | 15 | 0 |
| boot_failures | 0 | 18 |
| BUG:kernel_boot_crashed | 0 | 16 |
| BUG:kernel_boot_hang | 0 | 2 |
+-------------------------+------------+------------+
[ 0.000000] bootconsole [earlyser0] disabled
Elapsed time: 300
BUG: kernel boot crashed
qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-allyesdebian/gcc-5/7632469f927db4e8ebecfbc59bba16c14974a474/vmlinuz-4.4.0-rc6-00247-g7632469 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-1G-6/bisect_boot-1-debian-x86_64-2015-02-07.cgz-x86_64-allyesdebian-7632469f927db4e8ebecfbc59bba16c14974a474-20160220-100734-xc9ybt-1.yaml ARCH=x86_64 kconfig=x86_64-allyesdebian branch=linux-rt-devel/linux-4.4.y-rt commit=7632469f927db4e8ebecfbc59bba16c14974a474 BOOT_IMAGE=/pkg/linux/x86_64-allyesdebian/gcc-5/7632469f927db4e8ebecfbc59bba16c14974a474/vmlinuz-4.4.0-rc6-00247-g7632469 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-1G/debian-x86_64-2015-02-07.cgz/x86_64-allyesdebian/gcc-5/7632469f927db4e8ebecfbc59bba16c14974a474/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-1G-6::dhcp' -initrd /fs/sdg1/initrd-vm-kbuild-1G-6 -m 1536 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23005-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -device virtio-scsi-pci,id=scsi0 -drive file=/fs/sdg1/disk0-vm-kbuild-1G-6,if=none,id=hd0,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd0,scsi-id=1,lun=0 -drive file=/fs/sdg1/disk1-vm-kbuild-1G-6,if=none,id=hd1,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd1,scsi-id=1,lun=1 -drive file=/fs/sdg1/disk2-vm-kbuild-1G-6,if=none,id=hd2,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd2,scsi-id=1,lun=2 -drive file=/fs/sdg1/disk3-vm-kbuild-1G-6,if=none,id=hd3,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd3,scsi-id=1,lun=3 -drive file=/fs/sdg1/disk4-vm-kbuild-1G-6,if=none,id=hd4,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd4,scsi-id=1,lun=4 -pidfile /dev/shm/kboot/pid-vm-kbuild-1G-6 -serial file:/dev/shm/kboot/serial-vm-kbuild-1G-6 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
4 years, 10 months
[i2c: i2c-core] f3eab42515: WARNING: CPU: 0 PID: 1 at drivers/i2c/i2c-core.c:1930 i2c_register_driver()
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/0day-ci/linux Sudip-Mukherjee/i2c-i2c-core-do-not-use-bus-internal-data/20160307-195104
commit f3eab4251515a492aa38e515d9a2322c535ce358
Author: Sudip Mukherjee <sudipm.mukherjee(a)gmail.com>
AuthorDate: Mon Mar 7 17:19:17 2016 +0530
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Mon Mar 7 19:51:06 2016 +0800
i2c: i2c-core: do not use bus internal data
The variable p is a data structure which is used by the driver core
internally and it is not expected that busses will be directly accessing
these driver core internal only data.
Signed-off-by: Sudip Mukherjee <sudip.mukherjee(a)codethink.co.uk>
+----------------------------------------------------------------+------------+------------+------------+
| | 3bf58bb5da | f3eab42515 | f3eab42515 |
+----------------------------------------------------------------+------------+------------+------------+
| boot_successes | 80 | 0 | 0 |
| boot_failures | 2 | 13 | 13 |
| BUG:unable_to_handle_kernel | 1 | 13 | 13 |
| Oops | 1 | 13 | 13 |
| EIP_is_at_perf_callchain | 1 | | |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 1 | | |
| backtrace:__ubsan_handle_type_mismatch | 2 | | |
| backtrace:late_resume_init | 2 | | |
| backtrace:kernel_init_freeable | 2 | 13 | 13 |
| backtrace:iterate_dir | 1 | | |
| backtrace:SyS_getdents64 | 1 | | |
| WARNING:at_arch/x86/include/asm/fpu/internal.h:#fpu__restore() | 1 | | |
| WARNING:at_drivers/i2c/i2c-core.c:#i2c_register_driver() | 0 | 13 | 13 |
| EIP_is_at_blocking_notifier_chain_register | 0 | 13 | 13 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 13 | 13 |
| backtrace:i2c_register_driver | 0 | 13 | 13 |
| backtrace:i2c_init | 0 | 13 | 13 |
| backtrace:max7300_init | 0 | 13 | 13 |
| backtrace:max1586_pmic_init | 0 | 13 | 13 |
| backtrace:max8952_pmic_init | 0 | 13 | 13 |
| backtrace:tps51632_init | 0 | 13 | 13 |
| backtrace:tps_65023_init | 0 | 13 | 13 |
| backtrace:pm805_i2c_init | 0 | 13 | 13 |
| backtrace:wm#_module_init | 0 | 13 | 13 |
| backtrace:wm#x_i2c_init | 0 | 13 | 13 |
| backtrace:wm#_i2c_init | 0 | 13 | 13 |
| backtrace:tps65912_i2c_init | 0 | 13 | 13 |
| backtrace:tps80031_init | 0 | 13 | 13 |
| backtrace:max14577_i2c_init | 0 | 13 | 13 |
| backtrace:max77843_i2c_init | 0 | 13 | 13 |
| backtrace:max8997_i2c_init | 0 | 13 | 13 |
| backtrace:max8998_i2c_init | 0 | 13 | 13 |
| backtrace:pcf50633_init | 0 | 13 | 13 |
| backtrace:tps6586x_init | 0 | 13 | 13 |
| backtrace:aat2870_init | 0 | 13 | 13 |
| backtrace:palmas_i2c_init | 0 | 13 | 13 |
| backtrace:rc5t583_i2c_init | 0 | 13 | 13 |
| backtrace:sec_pmic_init | 0 | 13 | 13 |
| backtrace:lm#_i2c_init | 0 | 13 | 13 |
| backtrace:act8865_pmic_driver_init | 0 | 13 | 13 |
| backtrace:ltc3589_driver_init | 0 | 13 | 13 |
| backtrace:pv88090_regulator_driver_init | 0 | 13 | 13 |
| backtrace:bmp085_i2c_driver_init | 0 | 13 | 13 |
| backtrace:apds990x_driver_init | 0 | 13 | 13 |
| backtrace:tsl2550_driver_init | 0 | 13 | 13 |
| backtrace:eeprom_driver_init | 0 | 13 | 13 |
| backtrace:fsa9480_i2c_driver_init | 0 | 13 | 13 |
| backtrace:twl_driver_init | 0 | 13 | 13 |
| backtrace:twl6040_driver_init | 0 | 13 | 13 |
| backtrace:axp20x_i2c_driver_init | 0 | 13 | 13 |
| backtrace:da9150_driver_init | 0 | 13 | 13 |
| backtrace:rn5t618_i2c_driver_init | 0 | 13 | 13 |
| backtrace:sky81452_driver_init | 0 | 13 | 13 |
| backtrace:usb#_init | 0 | 13 | 13 |
| backtrace:cyapa_driver_init | 0 | 13 | 13 |
| backtrace:drv2665_driver_init | 0 | 13 | 13 |
| backtrace:drv2667_driver_init | 0 | 13 | 13 |
| backtrace:mpu3050_i2c_driver_init | 0 | 13 | 13 |
| backtrace:bus_register_notifier | 0 | 13 | 13 |
| backtrace:i2c_dev_init | 0 | 13 | 13 |
+----------------------------------------------------------------+------------+------------+------------+
[ 1.156740] RTC time: 20:24:25, date: 03/07/16
[ 1.159021] NET: Registered protocol family 16
[ 1.161817] ------------[ cut here ]------------
[ 1.163734] WARNING: CPU: 0 PID: 1 at drivers/i2c/i2c-core.c:1930 i2c_register_driver+0xaf/0x100()
[ 1.167712] Modules linked in:
[ 1.169387] CPU: 0 PID: 1 Comm: swapper Not tainted 4.5.0-rc3-00029-gf3eab42 #425
[ 1.172703] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.176318] d0e60000 00000000 d0e69e6c c15d695c d0e69ea0 c1078634 c20ef328 00000000
[ 1.180655] 00000001 c21d2ca0 0000078a c1b5af9f c1b5af9f c21d2ca0 c2700460 00000000
[ 1.184966] c2247920 d0e69eb0 c1078720 00000009 00000000 d0e69ed4 c1b5af9f d0c05120
[ 1.189307] Call Trace:
[ 1.190748] [<c15d695c>] dump_stack+0x16/0x1a
[ 1.192612] [<c1078634>] warn_slowpath_common+0x94/0xe0
[ 1.194670] [<c1b5af9f>] ? i2c_register_driver+0xaf/0x100
[ 1.196777] [<c1b5af9f>] ? i2c_register_driver+0xaf/0x100
[ 1.198876] [<c1078720>] warn_slowpath_null+0x20/0x30
[ 1.200887] [<c1b5af9f>] i2c_register_driver+0xaf/0x100
[ 1.202950] [<c17e1cfc>] ? class_compat_register+0x3c/0x60
[ 1.205067] [<c289fd9c>] i2c_init+0x5b/0x8d
[ 1.206928] [<c289fd41>] ? trace_event_define_fields_smbus_write+0xa/0xa
[ 1.209306] [<c10004a0>] do_one_initcall+0xd0/0x2e0
[ 1.211284] [<c289fd41>] ? trace_event_define_fields_smbus_write+0xa/0xa
[ 1.213670] [<c10a2ce3>] ? parse_args+0x233/0x640
[ 1.215613] [<c289fd41>] ? trace_event_define_fields_smbus_write+0xa/0xa
[ 1.218022] [<c10af62d>] ? preempt_count_sub+0xbd/0x140
[ 1.220077] [<c10bf774>] ? __wake_up+0x44/0x50
[ 1.221962] [<c2843faf>] ? kernel_init_freeable+0x1fc/0x30d
[ 1.224091] [<c2844033>] kernel_init_freeable+0x280/0x30d
[ 1.226206] [<c1e05d90>] kernel_init+0x10/0xe0
[ 1.228107] [<c1e0e9c8>] ret_from_kernel_thread+0x20/0x34
[ 1.230214] [<c1e05d80>] ? rest_init+0xb0/0xb0
[ 1.232107] ---[ end trace e92e6e4d14dc1d8f ]---
[ 1.245810] cpuidle: using governor menu
git bisect start f3eab4251515a492aa38e515d9a2322c535ce358 3bf58bb5da86d0a9e20f108a2f5163c4ce1a60dc --
# first bad commit: [f3eab4251515a492aa38e515d9a2322c535ce358] i2c: i2c-core: do not use bus internal data
git bisect good 3bf58bb5da86d0a9e20f108a2f5163c4ce1a60dc # 20:32 62+ 2 i2c: imx: remove extra spaces.
# extra tests with DEBUG_INFO
git bisect bad f3eab4251515a492aa38e515d9a2322c535ce358 # 20:34 0- 61 i2c: i2c-core: do not use bus internal data
# extra tests on HEAD of linux-review/Sudip-Mukherjee/i2c-i2c-core-do-not-use-bus-internal-data/20160307-195104
git bisect bad f3eab4251515a492aa38e515d9a2322c535ce358 # 20:34 0- 13 i2c: i2c-core: do not use bus internal data
# extra tests on tree/branch linux-review/Sudip-Mukherjee/i2c-i2c-core-do-not-use-bus-internal-data/20160307-195104
git bisect bad f3eab4251515a492aa38e515d9a2322c535ce358 # 20:34 0- 13 i2c: i2c-core: do not use bus internal data
# extra tests with first bad commit reverted
git bisect good 1a18b66147dfc4ef72c38b238a8ea1c2d9ee29e0 # 20:43 62+ 3 Revert "i2c: i2c-core: do not use bus internal data"
# extra tests on tree/branch linus/master
git bisect good f6cede5b49e822ebc41a099fe41ab4989f64e2cb # 20:46 62+ 0 Linux 4.5-rc7
# extra tests on tree/branch linux-next/master
git bisect good 078dd3bb709cb9848707609916f424a5de7f8e63 # 20:48 62+ 1 Add linux-next specific files for 20160307
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
initrd=quantal-core-i386.cgz
wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel $kernel
-initrd $initrd
-m 300
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
console=ttyS0,115200
console=tty0
vga=normal
root=/dev/ram0
rw
drbd.minor_count=8
)
"${kvm[@]}" --append "${append[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 10 months
[lkp] [nfs] 148558a301: BUG: unable to handle kernel NULL pointer dereference at 0000000000000028
by kernel test robot
FYI, we noticed the below changes on
https://github.com/0day-ci/linux Goldwyn-Rodrigues/ovl-Add-d_select_inode-to-reval-dentry-operations/20160302-021629
commit 148558a301d260972086b6c321d6341d3740cccf ("nfs: Store and use inode in nfs_open_context")
+------------------------------------------+------------+------------+
| | dc366564ea | 148558a301 |
+------------------------------------------+------------+------------+
| boot_successes | 16 | 20 |
| boot_failures | 1 | 11 |
| invoked_oom-killer:gfp_mask=0x | 1 | |
| Mem-Info | 1 | |
| Out_of_memory:Kill_process | 1 | |
| backtrace:_do_fork | 1 | |
| backtrace:SyS_clone | 1 | |
| backtrace:do_execveat_common | 1 | |
| backtrace:compat_SyS_execve | 1 | |
| BUG:unable_to_handle_kernel | 0 | 11 |
| Oops | 0 | 11 |
| RIP:alloc_nfs_open_context | 0 | 11 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 11 |
| backtrace:do_sys_open | 0 | 11 |
| backtrace:SyS_open | 0 | 11 |
+------------------------------------------+------------+------------+
[ 13.554531] NFS: Registering the id_resolver key type
[ 13.555827] Key type id_resolver registered
[ 13.556857] Key type id_legacy registered
[ 13.835351] BUG: unable to handle kernel NULL pointer dereference at 0000000000000028
[ 13.836041] IP: [<ffffffff812e3f31>] alloc_nfs_open_context+0x51/0x100
[ 13.836041] PGD 0
[ 13.836041] Oops: 0000 [#1] SMP
[ 13.836041] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sr_mod cdrom ata_generic pata_acpi ppdev snd_pcm snd_timer snd soundcore serio_raw pcspkr parport_pc parport floppy ata_piix acpi_cpufreq libata i2c_piix4
[ 13.844812] CPU: 0 PID: 343 Comm: wrapper Not tainted 4.5.0-rc6-00003-g148558a #1
[ 13.854820] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 13.854820] task: ffff88003f870000 ti: ffff88003f420000 task.ti: ffff88003f420000
[ 13.854820] RIP: 0010:[<ffffffff812e3f31>] [<ffffffff812e3f31>] alloc_nfs_open_context+0x51/0x100
[ 13.865228] RSP: 0018:ffff88003f423c38 EFLAGS: 00010282
[ 13.867037] RAX: ffff88003f4f4840 RBX: ffff88003f4f4840 RCX: 0000000000001c3c
[ 13.867037] RDX: 0000000000001c3b RSI: 0000000000000509 RDI: ffffffff81bea1b4
[ 13.867037] RBP: ffff88003f423c60 R08: 000000000001a660 R09: ffff880035801800
[ 13.867037] R10: ffff88003f4f4840 R11: 0000000000008241 R12: ffff88003f1926c0
[ 13.867037] R13: ffff88003f4f4780 R14: 0000000000000000 R15: 0000000000000002
[ 13.867037] FS: 00007f2e1fcba700(0000) GS:ffff880037200000(0000) knlGS:0000000000000000
[ 13.867037] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 13.867037] CR2: 0000000000000028 CR3: 000000003f636000 CR4: 00000000000006f0
[ 13.867037] Stack:
[ 13.867037] 0000000000008241 ffff88003f1926c0 ffff88003f189d48 ffff88003f423c88
[ 13.867037] 00000000000081b4 ffff88003f423d00 ffffffff812dee74 ffff88003f423d88
[ 13.867037] ffff88003e813000 ffff88003f1926c0 000081b400008009 0000000000000000
[ 13.867037] Call Trace:
[ 13.867037] [<ffffffff812dee74>] nfs_atomic_open+0x124/0x3f0
[ 13.867037] [<ffffffff81202c38>] path_openat+0x8f8/0x1360
[ 13.867037] [<ffffffff8120471e>] do_filp_open+0x7e/0xe0
[ 13.867037] [<ffffffff81203876>] ? getname_flags+0x56/0x1f0
[ 13.867037] [<ffffffff811d0d65>] ? kmem_cache_alloc+0x1a5/0x200
[ 13.867037] [<ffffffff81212366>] ? __alloc_fd+0x46/0x180
[ 13.867037] [<ffffffff811f2de6>] do_sys_open+0x116/0x1f0
[ 13.867037] [<ffffffff811f2ede>] SyS_open+0x1e/0x20
[ 13.867037] [<ffffffff818e4eee>] entry_SYSCALL_64_fastpath+0x12/0x6d
[ 13.867037] Code: 49 89 c5 0f 87 b5 00 00 00 48 8b 3d 3a 2a f3 00 ba 88 00 00 00 be c0 00 40 02 e8 9b ce ee ff 48 85 c0 48 89 c3 0f 84 9e 00 00 00 <49> 8b 7e 28 e8 06 4e 00 00 4d 85 e4 74 0a 49 8d 7c 24 58 e8 87
[ 13.867037] RIP [<ffffffff812e3f31>] alloc_nfs_open_context+0x51/0x100
[ 13.867037] RSP <ffff88003f423c38>
[ 13.867037] CR2: 0000000000000028
[ 13.936306] ---[ end trace 497d6290b4a58a55 ]---
[ 13.938047] Kernel panic - not syncing: Fatal exception
Thanks,
Kernel Test Robot
4 years, 10 months
Re: [LKP] [ppdev] e7223f1860: kernel BUG at drivers/base/driver.c:153!
by Sudip Mukherjee
On Wed, Mar 02, 2016 at 02:24:22PM -0700, Ross Zwisler wrote:
> On Mon, Feb 15, 2016 at 4:50 AM, Sudip Mukherjee
> <sudipm.mukherjee(a)gmail.com> wrote:
> > On Mon, Feb 15, 2016 at 04:20:45PM +0800, kernel test robot wrote:
> >> Greetings,
> >>
> >> 0day kernel testing robot got the below dmesg and the first bad commit is
> >>
> >> https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/char-misc.git char-misc-testing
> >>
> >> commit e7223f18603374d235d8bb0398532323e5f318b9
> >> Author: Sudip Mukherjee <sudipm.mukherjee(a)gmail.com>
> >> AuthorDate: Fri Feb 12 18:33:45 2016 +0530
> >> Commit: Greg Kroah-Hartman <gregkh(a)linuxfoundation.org>
> >> CommitDate: Sun Feb 14 17:43:50 2016 -0800
> >>
<snip>
>
> I've been hitting this error on my KVM system as well, and bisected to
> the same commit.
>
> The patch you have here solved the issue for me.
I will submit the patch today. I am sorry for the delay, i got held up
in some other things. Since you have tested this can I add your
Tested-by: to the patch?
regards
sudip
4 years, 10 months
[lkp] [cpufreq] 7379017843: INFO: suspicious RCU usage. ]
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/rafael/linux-pm.git bleeding-edge
commit 73790178433c63d2ed5a5c59b361e43887258030 ("cpufreq: Reduce cpufreq_update_util() overhead a bit")
+-------------------------------------+------------+------------+
| | bc8fa9af38 | 7379017843 |
+-------------------------------------+------------+------------+
| boot_successes | 6 | 0 |
| boot_failures | 0 | 8 |
| INFO:suspicious_RCU_usage | 0 | 8 |
| backtrace:schedule_preempt_disabled | 0 | 8 |
| invoked_oom-killer:gfp_mask=0x | 0 | 2 |
| Mem-Info | 0 | 2 |
| Out_of_memory:Kill_process | 0 | 2 |
| backtrace:do_execveat_common | 0 | 2 |
| backtrace:compat_SyS_execve | 0 | 2 |
| backtrace:_do_fork | 0 | 1 |
| backtrace:SyS_clone | 0 | 1 |
+-------------------------------------+------------+------------+
[ 1.776927] ftrace: allocating 48859 entries in 191 pages
[ 1.929599]
[ 1.932590] ===============================
[ 1.934983] [ INFO: suspicious RCU usage. ]
[ 1.938400] 4.5.0-rc2-00061-g7379017 #1 Not tainted
[ 1.940910] -------------------------------
[ 1.944325] drivers/cpufreq/cpufreq.c:150 suspicious rcu_dereference_check() usage!
[ 1.950208]
[ 1.950208] other info that might help us debug this:
[ 1.950208]
[ 1.956458]
[ 1.956458] rcu_scheduler_active = 1, debug_locks = 0
[ 1.961385] 1 lock held by swapper/0/0:
[ 1.963730] #0: (&rq->lock){-.....}, at: [<ffffffff81c64a27>] __schedule+0x1a5/0xecc
[ 1.969860]
[ 1.969860] stack backtrace:
[ 1.973903] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.5.0-rc2-00061-g7379017 #1
[ 1.979461] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.985275] 0000000000000000 ffffffff8227bd70 ffffffff8151fc7c ffffffff82287500
[ 1.991235] ffffffff8227bda0 ffffffff810de528 0000000000000000 00000000730320d6
[ 1.997185] 0000000000000400 0000000000000400 ffffffff8227bdd0 ffffffff81a5fd48
[ 2.003133] Call Trace:
[ 2.005258] [<ffffffff8151fc7c>] dump_stack+0x4b/0x63
[ 2.019401] [<ffffffff810de528>] lockdep_rcu_suspicious+0xf7/0x100
[ 2.021147] [<ffffffff81a5fd48>] cpufreq_update_util+0x77/0xa8
[ 2.022793] [<ffffffff810cb118>] set_next_entity+0x5f7/0x6cc
[ 2.050793] [<ffffffff810d0594>] pick_next_task_fair+0x13d/0x63c
[ 2.058104] [<ffffffff810d0594>] ? pick_next_task_fair+0x13d/0x63c
[ 2.059824] [<ffffffff81c64b35>] __schedule+0x2b3/0xecc
[ 2.061413] [<ffffffff81c657d6>] schedule+0x88/0x9d
[ 2.063934] [<ffffffff81c65a58>] schedule_preempt_disabled+0x15/0x1e
[ 2.068207] [<ffffffff81c5c7f9>] rest_init+0x130/0x140
[ 2.069763] [<ffffffff82615fd9>] start_kernel+0x4ee/0x4fb
[ 2.071567] [<ffffffff82615120>] ? early_idt_handler_array+0x120/0x120
[ 2.073526] [<ffffffff82615315>] x86_64_start_reservations+0x2a/0x2c
[ 2.075515] [<ffffffff8261544f>] x86_64_start_kernel+0x138/0x145
[ 2.077761] enabled ExtINT on CPU#0
[ 2.080484] ENABLING IO-APIC IRQs
[ 2.081952] init IO_APIC IRQs
Thanks,
Kernel Test Robot
4 years, 10 months
[lkp] [printk] 35e4e3140f: BUG: kernel boot crashed
by kernel test robot
FYI, we noticed the below changes on
https://github.com/0day-ci/linux Jan-Kara/printk-Make-printk-completely-async/20160303-000904
commit 35e4e3140fa952a074470ac4f06466c8b2f112c4 ("printk: Make printk() completely async")
[ 121.124939] Unregister pv shared memory for cpu 0
Elapsed time: 130
BUG: kernel boot crashed
qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-allyesdebian/gcc-5/35e4e3140fa952a074470ac4f06466c8b2f112c4/vmlinuz-4.5.0-rc6-00001-g35e4e31 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-client5-openwrt-ia32-12/bisect_boot-1-openwrt-i386.cgz-x86_64-allyesdebian-35e4e3140fa952a074470ac4f06466c8b2f112c4-20160303-26811-2ks6d5-2.yaml ARCH=x86_64 kconfig=x86_64-allyesdebian branch=linux-devel/devel-catchup-201603030044 commit=35e4e3140fa952a074470ac4f06466c8b2f112c4 BOOT_IMAGE=/pkg/linux/x86_64-allyesdebian/gcc-5/35e4e3140fa952a074470ac4f06466c8b2f112c4/vmlinuz-4.5.0-rc6-00001-g35e4e31 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-client5-openwrt-ia32/openwrt-i386.cgz/x86_64-allyesdebian/gcc-5/35e4e3140fa952a074470ac4f06466c8b2f112c4/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-client5-openwrt-ia32-12::dhcp drbd.minor_count=8' -initrd /fs/sda2/initrd-vm-client5-openwrt-ia32-12 -m 704 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sda2/disk0-vm-client5-openwrt-ia32-12,media=disk,if=virtio -drive file=/fs/sda2/disk1-vm-client5-openwrt-ia32-12,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-client5-openwrt-ia32-12 -serial file:/dev/shm/kboot/serial-vm-client5-openwrt-ia32-12 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
4 years, 10 months
[lkp] [vfs] e1ff3aaf03: BUG: using smp_processor_id() in preemptible [00000000] code: init/1
by kernel test robot
FYI, we noticed the below changes on
https://github.com/0day-ci/linux Waiman-Long/vfs-Use-per-cpu-list-for-SB-s-s_inodes-list/20160302-050444
commit e1ff3aaf031d89c13da1a772bc33b0f3b386629c ("vfs: Use per-cpu list for superblock's inode list")
[ 1.258924] Freeing unused kernel memory: 464K (cafc4000 - cb038000)
[ 1.260249] Write protecting the kernel text: 3276k
[ 1.261246] Write protecting the kernel read-only data: 2272k
[ 1.264224] BUG: using smp_processor_id() in preemptible [00000000] code: init/1
[ 1.265370] caller is debug_smp_processor_id+0x12/0x14
[ 1.266172] CPU: 0 PID: 1 Comm: init Not tainted 4.5.0-rc6-00004-ge1ff3aa #2
[ 1.267189] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.268465] 00000000 00000246 c004fd88 cab6d724 00000000 caef9b8c c004fdb8 cab80444
[ 1.269925] caef9b47 caef9b8c caeee200 00000000 c004d0bc 00000001 c004cd80 cb037a3c
[ 1.271202] d08dfaf8 000081a4 c004fdc0 cab8046a c004fdd4 cab7d40b d08dfa00 d3075600
[ 1.272475] Call Trace:
[ 1.272868] [<cab6d724>] dump_stack+0x55/0x6e
[ 1.273559] [<cab80444>] check_preemption_disabled+0xb7/0xcb
[ 1.274420] [<cab8046a>] debug_smp_processor_id+0x12/0x14
[ 1.275224] [<cab7d40b>] pcpu_list_add+0xf/0x3b
[ 1.275894] [<caaf935c>] inode_sb_list_add+0x19/0x1b
[ 1.276634] [<caafa91b>] new_inode+0x14/0x19
[ 1.277314] [<cab29c05>] ramfs_get_inode+0x11/0xe7
[ 1.278061] [<cab29d5a>] ? ramfs_mkdir+0x2b/0x2b
[ 1.278793] [<cab29cf4>] ramfs_mknod+0x19/0x54
[ 1.279434] [<cab29d6c>] ramfs_create+0x12/0x14
[ 1.280112] [<caaef592>] vfs_create+0x4c/0x6c
[ 1.280770] [<caaf1919>] path_openat+0x597/0xa39
[ 1.281489] [<cab8047b>] ? __this_cpu_preempt_check+0xf/0x11
[ 1.282386] [<caaf2c53>] do_filp_open+0x31/0x77
[ 1.283075] [<caaf0630>] ? put_page+0x30/0x30
[ 1.283713] [<caafbba5>] ? __alloc_fd+0x11a/0x124
[ 1.284417] [<caae71ee>] do_sys_open+0x54/0xcf
[ 1.285129] [<caae7281>] SyS_open+0x18/0x1a
[ 1.285835] [<caa00dd6>] do_syscall_32_irqs_on+0x3e/0x50
[ 1.286631] [<cad3111a>] entry_INT80_32+0x2a/0x2a
/etc/rcS.d/S00fbsetup: line 3: /sbin/modprobe: No such file or directory
Please wait: booting...
[ 1.313854] BUG: using smp_processor_id() in preemptible [00000000] code: rc/88
[ 1.314981] caller is debug_smp_processor_id+0x12/0x14
[ 1.315717] CPU: 1 PID: 88 Comm: rc Not tainted 4.5.0-rc6-00004-ge1ff3aa #2
[ 1.316745] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.318107] 00000000 00000246 d03cdcf4 cab6d724 00000001 caef9b8c d03cdd24 cab80444
[ 1.319489] caef9b47 caef9b8c caeee200 00000000 d014a43c 00000058 d014a100 cb03afbc
[ 1.320801] d092fbdc d3003ce4 d03cdd2c cab8046a d03cdd40 cab7d40b d092fae4 d014a100
[ 1.322095] Call Trace:
[ 1.322471] [<cab6d724>] dump_stack+0x55/0x6e
[ 1.323143] [<cab80444>] check_preemption_disabled+0xb7/0xcb
[ 1.323968] [<cab8046a>] debug_smp_processor_id+0x12/0x14
[ 1.324807] [<cab7d40b>] pcpu_list_add+0xf/0x3b
[ 1.325521] [<caaf935c>] inode_sb_list_add+0x19/0x1b
[ 1.326301] [<caafa91b>] new_inode+0x14/0x19
[ 1.326959] [<cab1bd6a>] proc_pid_make_inode+0xf/0x95
[ 1.327703] [<cab1b1f9>] ? oom_score_adj_write+0x150/0x150
[ 1.328517] [<cab1be01>] proc_pid_instantiate+0x11/0x98
[ 1.329279] [<cab1c8ce>] proc_pid_lookup+0x5d/0x71
[ 1.329985] [<cab1a0a4>] proc_root_lookup+0x11/0x25
[ 1.330700] [<caaeea41>] lookup_real+0x20/0x33
[ 1.331372] [<caaeee14>] __lookup_hash+0x2a/0x31
[ 1.332086] [<caaf0cd9>] walk_component+0x6b/0x11f
[ 1.332787] [<caaee9c4>] ? __inode_permission+0x4f/0x7d
[ 1.333565] [<cab2028f>] ? proc_self_readlink+0x57/0x57
[ 1.334378] [<caaf0ec6>] link_path_walk+0x139/0x359
[ 1.335164] [<cab19a24>] ? proc_reg_llseek+0x6e/0x6e
[ 1.335920] [<caaf115c>] path_lookupat+0x21/0xcb
[ 1.336603] [<caaf20ef>] filename_lookup+0x50/0x99
[ 1.337306] [<cab1965c>] ? unuse_pde+0x1d/0x1d
[ 1.337965] [<cab01caa>] ? simple_attr_release+0x12/0x12
[ 1.338763] [<caaf21ae>] user_path_at_empty+0x25/0x2c
[ 1.339512] [<caaeb179>] vfs_fstatat+0x4b/0x7f
[ 1.340176] [<caaeb1c0>] vfs_stat+0x13/0x15
[ 1.340863] [<caaeb714>] SyS_stat64+0x15/0x29
[ 1.341607] [<caa00cde>] ? prepare_exit_to_usermode+0x11/0x8b
[ 1.342500] [<caa00dd6>] do_syscall_32_irqs_on+0x3e/0x50
[ 1.343304] [<cad3111a>] entry_INT80_32+0x2a/0x2a
[ 1.344078] BUG: using smp_processor_id() in preemptible [00000000] code: rc/88
[ 1.345155] caller is debug_smp_processor_id+0x12/0x14
[ 1.346036] CPU: 1 PID: 88 Comm: rc Not tainted 4.5.0-rc6-00004-ge1ff3aa #2
[ 1.347115] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.348423] 00000000 00000246 d03cdd24 cab6d724 00000001 caef9b8c d03cdd54 cab80444
[ 1.349714] caef9b47 caef9b8c caeee200 00000000 d014a43c 00000058 d014a100 cb03afbc
[ 1.350986] d092f9bc cad45200 d03cdd5c cab8046a d03cdd70 cab7d40b d092f8c4 d014a100
[ 1.352254] Call Trace:
[ 1.352619] [<cab6d724>] dump_stack+0x55/0x6e
[ 1.353263] [<cab80444>] check_preemption_disabled+0xb7/0xcb
[ 1.354091] [<cab8046a>] debug_smp_processor_id+0x12/0x14
[ 1.354896] [<cab7d40b>] pcpu_list_add+0xf/0x3b
[ 1.355557] [<caaf935c>] inode_sb_list_add+0x19/0x1b
[ 1.356290] [<caafa91b>] new_inode+0x14/0x19
[ 1.356924] [<cab1bd6a>] proc_pid_make_inode+0xf/0x95
[ 1.357658] [<cab1c11b>] proc_pident_instantiate+0x15/0x8b
[ 1.358472] [<cab1c1eb>] proc_pident_lookup+0x5a/0x75
[ 1.359247] [<cab1c226>] proc_tgid_base_lookup+0xf/0x11
[ 1.360016] [<caaeea41>] lookup_real+0x20/0x33
[ 1.360666] [<caaeee14>] __lookup_hash+0x2a/0x31
[ 1.361344] [<caaf0cd9>] walk_component+0x6b/0x11f
[ 1.362057] [<cab19a24>] ? proc_reg_llseek+0x6e/0x6e
[ 1.362785] [<caaf119c>] path_lookupat+0x61/0xcb
[ 1.363457] [<caaf20ef>] filename_lookup+0x50/0x99
[ 1.364158] [<cab1965c>] ? unuse_pde+0x1d/0x1d
[ 1.364827] [<cab01caa>] ? simple_attr_release+0x12/0x12
[ 1.365596] [<caaf21ae>] user_path_at_empty+0x25/0x2c
[ 1.366351] [<caaeb179>] vfs_fstatat+0x4b/0x7f
[ 1.367036] [<caaeb1c0>] vfs_stat+0x13/0x15
[ 1.367703] [<caaeb714>] SyS_stat64+0x15/0x29
[ 1.368396] [<caa00cde>] ? prepare_exit_to_usermode+0x11/0x8b
[ 1.369266] [<caa00dd6>] do_syscall_32_irqs_on+0x3e/0x50
[ 1.370053] [<cad3111a>] entry_INT80_32+0x2a/0x2a
[ 1.374941] BUG: using smp_processor_id() in preemptible [00000000] code: S03udev/91
[ 1.375593] caller is debug_smp_processor_id+0x12/0x14
[ 1.376010] CPU: 0 PID: 91 Comm: S03udev Not tainted 4.5.0-rc6-00004-ge1ff3aa #2
[ 1.376611] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.377359] 00000000 00000246 d03b3d44 cab6d724 00000000 caef9b8c d03b3d74 cab80444
[ 1.378153] caef9b47 caef9b8c caeee200 00000000 d01443fc 0000005b d01440c0 cb03afbc
[ 1.378921] d300311c d3003ce4 d03b3d7c cab8046a d03b3d90 cab7d40b d3003024 d01440c0
[ 1.379648] Call Trace:
[ 1.379860] [<cab6d724>] dump_stack+0x55/0x6e
[ 1.380219] [<cab80444>] check_preemption_disabled+0xb7/0xcb
[ 1.380703] [<cab8046a>] debug_smp_processor_id+0x12/0x14
[ 1.381160] [<cab7d40b>] pcpu_list_add+0xf/0x3b
[ 1.381563] [<caaf935c>] inode_sb_list_add+0x19/0x1b
[ 1.382007] [<caafa91b>] new_inode+0x14/0x19
[ 1.382392] [<cab1bd6a>] proc_pid_make_inode+0xf/0x95
[ 1.382824] [<cab1b1f9>] ? oom_score_adj_write+0x150/0x150
[ 1.383288] [<cab1be01>] proc_pid_instantiate+0x11/0x98
[ 1.383730] [<cab1c8ce>] proc_pid_lookup+0x5d/0x71
[ 1.384121] [<cab1a0a4>] proc_root_lookup+0x11/0x25
[ 1.384531] [<caaeea41>] lookup_real+0x20/0x33
[ 1.385051] [<caaeee14>] __lookup_hash+0x2a/0x31
[ 1.385668] [<caaf0cd9>] walk_component+0x6b/0x11f
[ 1.386320] [<caaee9c4>] ? __inode_permission+0x4f/0x7d
[ 1.387027] [<cab2028f>] ? proc_self_readlink+0x57/0x57
[ 1.387722] [<caaf0ec6>] link_path_walk+0x139/0x359
[ 1.388354] [<cab19a24>] ? proc_reg_llseek+0x6e/0x6e
[ 1.388974] [<caaf115c>] path_lookupat+0x21/0xcb
[ 1.389557] [<caaf20ef>] filename_lookup+0x50/0x99
[ 1.390179] [<cab1965c>] ? unuse_pde+0x1d/0x1d
[ 1.390782] [<cab01caa>] ? simple_attr_release+0x12/0x12
[ 1.391493] [<caaf21ae>] user_path_at_empty+0x25/0x2c
[ 1.392178] [<caae6102>] SYSC_faccessat+0x9d/0x16f
[ 1.392820] [<caae6d0d>] SyS_faccessat+0x11/0x13
[ 1.393422] [<caa00dd6>] do_syscall_32_irqs_on+0x3e/0x50
[ 1.394080] [<cad3111a>] entry_INT80_32+0x2a/0x2a
[ 1.394773] BUG: using smp_processor_id() in preemptible [00000000] code: S03udev/91
[ 1.395774] caller is debug_smp_processor_id+0x12/0x14
[ 1.396453] CPU: 0 PID: 91 Comm: S03udev Not tainted 4.5.0-rc6-00004-ge1ff3aa #2
[ 1.397423] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.398540] 00000000 00000246 d03b3d74 cab6d724 00000000 caef9b8c d03b3da4 cab80444
[ 1.399629] caef9b47 caef9b8c caeee200 00000000 d01443fc 0000005b d01440c0 cb03afbc
[ 1.400773] d08e4e1c cad45200 d03b3dac cab8046a d03b3dc0 cab7d40b d08e4d24 d01440c0
[ 1.401947] Call Trace:
[ 1.402284] [<cab6d724>] dump_stack+0x55/0x6e
[ 1.402867] [<cab80444>] check_preemption_disabled+0xb7/0xcb
[ 1.403590] [<cab8046a>] debug_smp_processor_id+0x12/0x14
[ 1.404262] [<cab7d40b>] pcpu_list_add+0xf/0x3b
[ 1.404859] [<caaf935c>] inode_sb_list_add+0x19/0x1b
[ 1.405505] [<caafa91b>] new_inode+0x14/0x19
[ 1.406091] [<cab1bd6a>] proc_pid_make_inode+0xf/0x95
[ 1.406777] [<cab1c11b>] proc_pident_instantiate+0x15/0x8b
[ 1.407515] [<cab1c1eb>] proc_pident_lookup+0x5a/0x75
[ 1.408170] [<cab1c226>] proc_tgid_base_lookup+0xf/0x11
[ 1.408837] [<caaeea41>] lookup_real+0x20/0x33
[ 1.409390] [<caaeee14>] __lookup_hash+0x2a/0x31
[ 1.409994] [<caaf0cd9>] walk_component+0x6b/0x11f
[ 1.410621] [<cab19a24>] ? proc_reg_llseek+0x6e/0x6e
[ 1.411295] [<caaf119c>] path_lookupat+0x61/0xcb
[ 1.411909] [<caaf20ef>] filename_lookup+0x50/0x99
[ 1.412530] [<cab1965c>] ? unuse_pde+0x1d/0x1d
[ 1.413087] [<cab01caa>] ? simple_attr_release+0x12/0x12
[ 1.413759] [<caaf21ae>] user_path_at_empty+0x25/0x2c
[ 1.414411] [<caae6102>] SYSC_faccessat+0x9d/0x16f
[ 1.415071] [<caae6d0d>] SyS_faccessat+0x11/0x13
[ 1.415695] [<caa00dd6>] do_syscall_32_irqs_on+0x3e/0x50
[ 1.416378] [<cad3111a>] entry_INT80_32+0x2a/0x2a
Starting udev
[ 1.418942] BUG: using smp_processor_id() in preemptible [00000000] code: awk/92
[ 1.419935] caller is debug_smp_processor_id+0x12/0x14
[ 1.420616] CPU: 0 PID: 92 Comm: awk Not tainted 4.5.0-rc6-00004-ge1ff3aa #2
[ 1.421510] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.422587] 00000000 00000246 d039fd0c cab6d724 00000000 caef9b8c d039fd3c cab80444
[ 1.423729] caef9b47 caef9b8c caeee200 00000000 d014a43c 0000005c d014a100 cb03afbc
[ 1.424909] d092fbdc d3003ce4 d039fd44 cab8046a d039fd58 cab7d40b d092fae4 d014a100
[ 1.426082] Call Trace:
[ 1.426404] [<cab6d724>] dump_stack+0x55/0x6e
[ 1.426965] [<cab80444>] check_preemption_disabled+0xb7/0xcb
[ 1.427667] [<cab8046a>] debug_smp_processor_id+0x12/0x14
[ 1.428361] [<cab7d40b>] pcpu_list_add+0xf/0x3b
[ 1.428972] [<caaf935c>] inode_sb_list_add+0x19/0x1b
[ 1.429638] [<caafa91b>] new_inode+0x14/0x19
[ 1.430223] [<cab1bd6a>] proc_pid_make_inode+0xf/0x95
[ 1.430896] [<cab1b1f9>] ? oom_score_adj_write+0x150/0x150
[ 1.431603] [<cab1be01>] proc_pid_instantiate+0x11/0x98
[ 1.432309] [<cab1c8ce>] proc_pid_lookup+0x5d/0x71
[ 1.432946] [<cab1a0a4>] proc_root_lookup+0x11/0x25
[ 1.433575] [<caaeea41>] lookup_real+0x20/0x33
[ 1.434136] [<caaeee14>] __lookup_hash+0x2a/0x31
[ 1.434730] [<caaf0cd9>] walk_component+0x6b/0x11f
[ 1.435142] [<caaee9c4>] ? __inode_permission+0x4f/0x7d
[ 1.435585] [<cab2028f>] ? proc_self_readlink+0x57/0x57
[ 1.436055] [<caaf0ec6>] link_path_walk+0x139/0x359
[ 1.436489] [<cab19a24>] ? proc_reg_llseek+0x6e/0x6e
[ 1.436940] [<caaf151b>] path_openat+0x199/0xa39
[ 1.437334] [<caa68105>] ? lock_release+0xf8/0x25e
[ 1.437750] [<caaf2c53>] do_filp_open+0x31/0x77
[ 1.438122] [<cab1965c>] ? unuse_pde+0x1d/0x1d
Thanks,
Kernel Test Robot
4 years, 10 months
[lkp] [block] 3d63546519: kernel BUG at block/bio.c:163!
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/axboe/linux-block.git io-streamid
commit 3d63546519428f2ed1b79e93e4321cf37c5a0d83 ("block: add support for carrying a stream ID in a bio")
udevd[279]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv of:Ni2c-test-busT<NULL>Cunittest-i2c-bus': No such file or directory
[ 3.768664] ------------[ cut here ]------------
[ 3.768664] ------------[ cut here ]------------
[ 3.769406] kernel BUG at block/bio.c:163!
[ 3.769406] kernel BUG at block/bio.c:163!
[ 3.770299] invalid opcode: 0000 [#1]
[ 3.770299] invalid opcode: 0000 [#1] SMP SMP DEBUG_PAGEALLOC DEBUG_PAGEALLOC
[ 3.771226] Modules linked in:
[ 3.771226] Modules linked in:
[ 3.771682] CPU: 0 PID: 282 Comm: blkid Not tainted 4.5.0-rc6-00007-g3d63546 #1
[ 3.771682] CPU: 0 PID: 282 Comm: blkid Not tainted 4.5.0-rc6-00007-g3d63546 #1
[ 3.772781] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.772781] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 3.774100] task: 83e38000 ti: 83a9c000 task.ti: 83a9c000
[ 3.774100] task: 83e38000 ti: 83a9c000 task.ti: 83a9c000
[ 3.774886] EIP: 0060:[<7923afd4>] EFLAGS: 00010206 CPU: 0
[ 3.774886] EIP: 0060:[<7923afd4>] EFLAGS: 00010206 CPU: 0
[ 3.775700] EIP is at bvec_free+0x2a/0x4e
[ 3.775700] EIP is at bvec_free+0x2a/0x4e
[ 3.776321] EAX: 79b19694 EBX: 0000000f ECX: 00000000 EDX: 00000001
[ 3.776321] EAX: 79b19694 EBX: 0000000f ECX: 00000000 EDX: 00000001
[ 3.777267] ESI: 82c24000 EDI: 83c30b48 EBP: 83a9dc08 ESP: 83a9dbfc
[ 3.777267] ESI: 82c24000 EDI: 83c30b48 EBP: 83a9dc08 ESP: 83a9dbfc
[ 3.778188] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
[ 3.778188] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
[ 3.778986] CR0: 8005003b CR2: 08e1d144 CR3: 0bb73000 CR4: 00000690
[ 3.778986] CR0: 8005003b CR2: 08e1d144 CR3: 0bb73000 CR4: 00000690
[ 3.779900] Stack:
[ 3.779900] Stack:
[ 3.780206] 83b1fd60
[ 3.780206] 83b1fd60 83c30940 83c30940 83b1fd60 83b1fd60 83a9dc18 83a9dc18 7923b021 7923b021 83b1fd60 83b1fd60 83b1fba0 83b1fba0 83a9dc28 83a9dc28
[ 3.781451] 7923b076
[ 3.781451] 7923b076 83b1fd60 83b1fd60 83b1fba0 83b1fba0 83a9dc38 83a9dc38 7923b257 7923b257 83c50000 83c50000 00000000 00000000 83a9dc5c 83a9dc5c
[ 3.782689] 79241bfe
[ 3.782689] 79241bfe 00000000 00000000 00000000 00000000 0001fe00 0001fe00 0001fe00 0001fe00 83c50000 83c50000 00000000 00000000 00000000 00000000
[ 3.783933] Call Trace:
[ 3.783933] Call Trace:
[ 3.784298] [<7923b021>] bio_free+0x29/0x41
[ 3.784298] [<7923b021>] bio_free+0x29/0x41
[ 3.784942] [<7923b076>] bio_put+0x3d/0x41
[ 3.784942] [<7923b076>] bio_put+0x3d/0x41
[ 3.785560] [<7923b257>] bio_endio+0x71/0x8d
[ 3.785560] [<7923b257>] bio_endio+0x71/0x8d
[ 3.786199] [<79241bfe>] blk_update_request+0x1d9/0x2bf
[ 3.786199] [<79241bfe>] blk_update_request+0x1d9/0x2bf
[ 3.787002] [<79248db9>] blk_mq_end_request+0x16/0x29
[ 3.787002] [<79248db9>] blk_mq_end_request+0x16/0x29
[ 3.787757] [<794d16de>] end_cmd+0x3d/0xb4
[ 3.787757] [<794d16de>] end_cmd+0x3d/0xb4
[ 3.788405] [<794d1775>] null_softirq_done_fn+0x20/0x22
[ 3.788405] [<794d1775>] null_softirq_done_fn+0x20/0x22
[ 3.789203] [<79248ee9>] __blk_mq_complete_request+0xac/0xb8
[ 3.789203] [<79248ee9>] __blk_mq_complete_request+0xac/0xb8
[ 3.790037] [<792495d8>] blk_mq_complete_request+0x31/0x35
[ 3.790037] [<792495d8>] blk_mq_complete_request+0x31/0x35
[ 3.790847] [<794d19eb>] null_handle_cmd+0x3e/0x62
[ 3.790847] [<794d19eb>] null_handle_cmd+0x3e/0x62
[ 3.791588] [<794d1ae0>] null_queue_rq+0x66/0x6e
[ 3.791588] [<794d1ae0>] null_queue_rq+0x66/0x6e
[ 3.792305] [<79249fcc>] __blk_mq_run_hw_queue+0x1b9/0x2a4
[ 3.792305] [<79249fcc>] __blk_mq_run_hw_queue+0x1b9/0x2a4
[ 3.793132] [<79249ded>] blk_mq_run_hw_queue+0x70/0x96
[ 3.793132] [<79249ded>] blk_mq_run_hw_queue+0x70/0x96
[ 3.793892] [<7924b0c5>] blk_mq_insert_requests+0x12f/0x19a
[ 3.793892] [<7924b0c5>] blk_mq_insert_requests+0x12f/0x19a
[ 3.794729] [<7924b7d8>] blk_mq_flush_plug_list+0xe1/0xeb
[ 3.794729] [<7924b7d8>] blk_mq_flush_plug_list+0xe1/0xeb
[ 3.795546] [<79242cbc>] blk_flush_plug_list+0x88/0x1d0
[ 3.795546] [<79242cbc>] blk_flush_plug_list+0x88/0x1d0
[ 3.796323] [<79243020>] blk_finish_plug+0x23/0x30
[ 3.796323] [<79243020>] blk_finish_plug+0x23/0x30
[ 3.797069] [<791388c2>] ? blkdev_write_begin+0x25/0x25
[ 3.797069] [<791388c2>] ? blkdev_write_begin+0x25/0x25
[ 3.797832] [<790ddd41>] __do_page_cache_readahead+0x220/0x24b
[ 3.797832] [<790ddd41>] __do_page_cache_readahead+0x220/0x24b
[ 3.798696] [<790ddf22>] ondemand_readahead+0x1b6/0x1c0
[ 3.798696] [<790ddf22>] ondemand_readahead+0x1b6/0x1c0
[ 3.799502] [<790ddf94>] page_cache_async_readahead+0x68/0x73
[ 3.799502] [<790ddf94>] page_cache_async_readahead+0x68/0x73
[ 3.800364] [<790d4fb6>] generic_file_read_iter+0x21e/0x58c
[ 3.800364] [<790d4fb6>] generic_file_read_iter+0x21e/0x58c
[ 3.801233] [<79138783>] ? seqcount_lockdep_reader_access+0x5e/0x6c
[ 3.801233] [<79138783>] ? seqcount_lockdep_reader_access+0x5e/0x6c
[ 3.802202] [<79138bcf>] ? i_size_read+0x49/0x5c
[ 3.802202] [<79138bcf>] ? i_size_read+0x49/0x5c
[ 3.802883] [<79138d50>] blkdev_read_iter+0x4a/0x53
[ 3.802883] [<79138d50>] blkdev_read_iter+0x4a/0x53
[ 3.803618] [<7910ab29>] __vfs_read+0x68/0xa8
[ 3.803618] [<7910ab29>] __vfs_read+0x68/0xa8
[ 3.804316] [<7910b5f9>] vfs_read+0xa2/0xbb
[ 3.804316] [<7910b5f9>] vfs_read+0xa2/0xbb
[ 3.804961] [<7910bc83>] SyS_read+0x4f/0x7c
[ 3.804961] [<7910bc83>] SyS_read+0x4f/0x7c
[ 3.805591] [<790017a9>] do_fast_syscall_32+0xc2/0xfc
[ 3.805591] [<790017a9>] do_fast_syscall_32+0xc2/0xfc
[ 3.806362] [<796083da>] sysenter_past_esp+0x3b/0x5d
[ 3.806362] [<796083da>] sysenter_past_esp+0x3b/0x5d
[ 3.807137] Code:
[ 3.807137] Code: c3 c3 55 55 89 89 e5 e5 57 57 56 56 53 53 e8 e8 df df df df 3c 3c 00 00 89 89 d6 d6 31 31 d2 d2 83 83 f9 f9 05 05 0f 0f 97 97 c2 c2 89 89 cb cb 89 89 c7 c7 31 31 c9 c9 b8 b8 94 94 96 96 b1 b1 79 79 e8 e8 f4 f4 18 18 e7 e7 ff ff 83 83 fb fb 05 05 76 76 02 02 <0f> <0f> 0b 0b 75 75 0b 0b 89 89 fa fa 89 89 f0 f0 e8 e8 3c 3c b1 b1 e9 e9 ff ff eb eb 10 10 6b 6b db db 0c 0c 89 89 f2 f2 8b 8b
[ 3.810615] EIP: [<7923afd4>]
[ 3.810615] EIP: [<7923afd4>] bvec_free+0x2a/0x4ebvec_free+0x2a/0x4e SS:ESP 0068:83a9dbfc
SS:ESP 0068:83a9dbfc
[ 3.811639] ---[ end trace e05eb3f5eb9a09e1 ]---
[ 3.811639] ---[ end trace e05eb3f5eb9a09e1 ]---
Thanks,
Kernel Test Robot
4 years, 10 months
Re: [LKP] [lkp] [printk] 34578dc67f: EIP is at vprintk_emit+0x1ea/0x600
by Sergey Senozhatsky
On (02/23/16 22:15), Sergey Senozhatsky wrote:
> On (02/23/16 08:55), kernel test robot wrote:
> > [ 33.497678] ### dt-test ### of_unittest_destroy_tracked_overlays: overlay destroy failed for #6
> > [ 33.497693] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper:1]
> > [ 33.497693] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper:1]
> > [ 33.497695] Modules linked in:
> > [ 33.497695] Modules linked in:
> > [ 33.497696] irq event stamp: 69018756
> > [ 33.497696] irq event stamp: 69018756
>
> how may consoles do you have registered? is it possible that your
> call_console_drivers() loop push the same data to different consoles,
> but the messages end up in one place (serial port, etc... dunno)?
> does it happen to all of the messages?
oh... didn't notice there was a dmesg.xz attached.
ok, so everything is "doubled" in your dmesg, starting from "console [ttyS0] enabled" line
254 [ 0.000000] vmalloc : 0xc47e0000 - 0xff7fe000 ( 944 MB)
255 [ 0.000000] lowmem : 0xb0000000 - 0xc3fe0000 ( 319 MB)
256 [ 0.000000] .init : 0xc0dc4000 - 0xc0e3a000 ( 472 kB)
257 [ 0.000000] .data : 0xc0a0a040 - 0xc0dc2560 (3809 kB)
258 [ 0.000000] .text : 0xc0600000 - 0xc0a0a040 (4136 kB)
259 [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
260 [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
261 [ 0.000000] Running RCU self tests
262 [ 0.000000] NR_IRQS:2304 nr_irqs:256 16
263 [ 0.000000] CPU 0 irqstacks, hard=b002a000 soft=b002c000
264 [ 0.000000] console [ttyS0] enabled
265 [ 0.000000] console [ttyS0] enabled
266 [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
267 [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
268 [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
269 [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
270 [ 0.000000] ... MAX_LOCK_DEPTH: 48
271 [ 0.000000] ... MAX_LOCK_DEPTH: 48
272 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
273 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
274 [ 0.000000] ... CLASSHASH_SIZE: 4096
275 [ 0.000000] ... CLASSHASH_SIZE: 4096
276 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768
277 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768
278 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536
279 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536
280 [ 0.000000] ... CHAINHASH_SIZE: 32768
281 [ 0.000000] ... CHAINHASH_SIZE: 32768
282 [ 0.000000] memory used by lock dependency info: 5167 kB
283 [ 0.000000] memory used by lock dependency info: 5167 kB
284 [ 0.000000] per task-struct memory footprint: 2112 bytes
285 [ 0.000000] per task-struct memory footprint: 2112 bytes
286 [ 0.000000] ------------------------
287 [ 0.000000] ------------------------
288 [ 0.000000] | Locking API testsuite:
289 [ 0.000000] | Locking API testsuite:
290 [ 0.000000] ----------------------------------------------------------------------------
291 [ 0.000000] ----------------------------------------------------------------------------
292 [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem |
293 [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem |
294 [ 0.000000] --------------------------------------------------------------------------
295 [ 0.000000] --------------------------------------------------------------------------
looking at your Kernel command line
[ 0.000000] Kernel command line: root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-i386-53/bisect_boot-1-yocto-minimal-i386.cgz-i386-randconfig-h1-02192137-34578dc67f38c02ccbe696e4099967884caa8e15-20160220-72722-vao2m5-0.yaml ARCH=i386 kconfig=i386-randconfig-h1-02192137 branch=linux-next/master commi t=34578dc67f38c02ccbe696e4099967884caa8e15 BOOT_IMAGE=/pkg/linux/i386-randconfig-h1-02192137/gcc-5/34578dc67f38c02ccbe696e4099967884caa8e15/vmlinuz-4.5.0-rc4-00295-g34578dc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-i386/yocto-minimal-i386.cgz/i386-randconfig-h1-02192137/gcc-5/34578dc67f38c02ccbe69 6e4099967884caa8e15/9 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yo cto-i386-53::dhcp drbd.minor_count=8
- earlyprintk=ttyS0,115200
- console=ttyS0,115200
- console=tty0
so you have two consoles: ttyS0 and tty0?
and I see "bootconsole [earlyser0] enabled" but no "bootconsole [earlyser0] disabled" message, which
I'd expect to see...
So how may consoles do you have? 2 or 3? so call_console_drivers() has to ->write()
every log line N times; at least to ttyS0->write() and to tty0->write()?
-ss
4 years, 10 months