[lkp] [powercap] 897ce43d3c: kmsg.intel_rapl:invalid_domain_id
by kernel test robot
FYI, we noticed the below changes on
https://github.com/0day-ci/linux Srinivas-Pandruvada/powercap-intel_rapl-PSys-support/20160319-021553
commit 897ce43d3cc5556155782f7d4b0c2a61db4f8e07 ("powercap: intel_rapl: PSys support")
As below, the log "intel_rapl: invalid domain id 4" showed with your commit.
[ 16.863544] intel_telemetry_core Init
[ 16.864106] intel_telemetry: version 1.0.0 loaded
[ 16.868478] Intel(R) PCI-E Non-Transparent Bridge Driver 2.0
[ 16.869470] Software Queue-Pair Transport over NTB, version 4
[ 16.870516] fake-fmc-carrier: mezzanine 0
[ 16.871133] Manufacturer: fake-vendor
[ 16.871754] Product name: fake-design-for-testing
[ 16.872642] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[ 16.886790] fmc_trivial: probe of fake-design-for-testing-f001 failed with error -95
[ 16.888022] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[ 16.889074] fmc_write_eeprom fake-design-for-testing-f001: fmc_write_eeprom: no busid passed, refusing all cards
[ 16.890604] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[ 16.891965] fmc_chardev fake-design-for-testing-f001: Created misc device "fake-design-for-testing-f001"
[ 16.934003] intel_rapl: invalid domain id 4
[ 16.934657] intel_rapl: no valid rapl domains found in package 0
[ 16.936516] microcode: CPU0 sig=0x206a1, pf=0x1, revision=0x1
[ 16.937546] microcode: Microcode Update Driver: v2.01 <tigran(a)aivazian.fsnet.co.uk>, Peter Oruba
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu SandyBridge -kernel /pkg/linux/x86_64-randconfig-s2-03190504/gcc-5/897ce43d3cc5556155782f7d4b0c2a61db4f8e07/vmlinuz-4.5.0-01727-g897ce43 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-x86_64-34/bisect_boot-1-yocto-minimal-x86_64.cgz-x86_64-randconfig-s2-03190504-897ce43d3cc5556155782f7d4b0c2a61db4f8e07-20160319-42711-18o68i1-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s2-03190504 branch=linux-devel/devel-hourly-2016031901 commit=897ce43d3cc5556155782f7d4b0c2a61db4f8e07 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s2-03190504/gcc-5/897ce43d3cc5556155782f7d4b0c2a61db4f8e07/vmlinuz-4.5.0-01727-g897ce43 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-x86_64/yocto-minimal-x86_64.cgz/x86_64-randconfig-s2-03190504/gcc-5/897ce43d3cc5556155782f7d4b0c2a61db4f8e07/1 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-yocto-x86_64-34::dhcp drbd.minor_count=8' -initrd /fs/sda1/initrd-vm-kbuild-yocto-x86_64-34 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sda1/disk0-vm-kbuild-yocto-x86_64-34,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-x86_64-34 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-x86_64-34 -daemonize -display none -monitor null
Thanks,
Xiaolong Ye.
5 years, 8 months
[lkp] [drm/dp_helper] 31f8862c6e: No primary result change, 128.2% piglit.time.voluntary_context_switches
by kernel test robot
FYI, we noticed that piglit.time.voluntary_context_switches +917.2% change with your commit.
https://github.com/0day-ci/linux Lyude/drm-dp_helper-retry-on-ETIMEDOUT-in-drm_dp_dpcd_access/20160317-234351
commit 31f8862c6e6303223e946e6fcbdfa7f87274baef ("drm/dp_helper: retry on -ETIMEDOUT in drm_dp_dpcd_access()")
=========================================================================================
compiler/group/kconfig/rootfs/tbox_group/testcase:
gcc-4.9/igt-071/x86_64-rhel/debian-x86_64-2015-02-07.cgz/snb-black/piglit
commit:
cf481068cdd430a22425d7712c8deeb25efdedc1
31f8862c6e6303223e946e6fcbdfa7f87274baef
cf481068cdd430a2 31f8862c6e6303223e946e6fcb
---------------- --------------------------
%stddev %change %stddev
\ | \
111.96 ± 0% +128.2% 255.52 ± 0% piglit.time.elapsed_time
111.96 ± 0% +128.2% 255.52 ± 0% piglit.time.elapsed_time.max
8.25 ± 5% -39.4% 5.00 ± 0% piglit.time.percent_of_cpu_this_job_got
31676 ± 0% +917.2% 322222 ± 0% piglit.time.voluntary_context_switches
111.96 ± 0% +128.2% 255.52 ± 0% time.elapsed_time
111.96 ± 0% +128.2% 255.52 ± 0% time.elapsed_time.max
115.50 ± 1% +31.2% 151.50 ± 13% time.involuntary_context_switches
8.25 ± 5% -39.4% 5.00 ± 0% time.percent_of_cpu_this_job_got
8.31 ± 0% +45.7% 12.12 ± 0% time.system_time
31676 ± 0% +917.2% 322222 ± 0% time.voluntary_context_switches
snb-black: Sandy Bridge
Memory: 8G
piglit.time.voluntary_context_switches
350000 ++-----------------------------------------------------------------+
| OO OO |
300000 ++ |
| |
250000 ++ |
| |
200000 ++ |
| |
150000 ++ |
| |
100000 OO OO OO OO OO OOO OO OO OO OO |
| |
50000 ++ |
**.**.**. *.**.***.**.**.**.**.**.***.**.**.**.**.***.**.**.**.**.**
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 Ye.
5 years, 8 months
[lkp] [cpufreq] 9be4fd2c77: No primary result change, 56.4% fsmark.time.involuntary_context_switches
by kernel test robot
FYI, we noticed that fsmark.time.involuntary_context_switches +56.4% change with your commit:
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 9be4fd2c7723a3057b0b39676fe4c8d5fd7118a4 ("cpufreq: governor: Replace timers with utilization update callbacks")
=========================================================================================
compiler/disk/filesize/fs2/fs/iterations/kconfig/nr_directories/nr_files_per_directory/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase:
gcc-4.9/1HDD/8K/nfsv4/ext4/1x/x86_64-rhel/16d/256fpd/32t/debian-x86_64-2015-02-07.cgz/fsyncBeforeClose/lkp-ws02/400M/fsmark
commit:
a4675fbc4a7abe072ac6ba38c252f22a91ebcd94
9be4fd2c7723a3057b0b39676fe4c8d5fd7118a4
a4675fbc4a7abe07 9be4fd2c7723a3057b0b39676f
---------------- --------------------------
%stddev %change %stddev
\ | \
70792 ± 0% +56.4% 110713 ± 1% fsmark.time.involuntary_context_switches
108756 ± 2% -10.4% 97492 ± 6% meminfo.DirectMap4k
70792 ± 0% +56.4% 110713 ± 1% time.involuntary_context_switches
21.50 ± 57% +48.8% 32.00 ± 2% turbostat.Avg_MHz
92879 ± 1% -12.2% 81528 ± 2% softirqs.RCU
107180 ± 1% -19.4% 86414 ± 0% softirqs.SCHED
7477 ± 0% +7.5% 8036 ± 0% vmstat.system.cs
710.75 ± 0% +92.2% 1366 ± 2% vmstat.system.in
1.165e+09 ± 0% -12.3% 1.022e+09 ± 3% cpuidle.C3-NHM.time
266839 ± 1% -20.5% 212259 ± 2% cpuidle.C3-NHM.usage
58012774 ± 4% +26.7% 73508891 ± 2% cpuidle.POLL.time
lkp-ws02: Westmere-EP
Memory: 16G
fsmark.time.involuntary_context_switches
115000 ++-----------------------------------------------------------------+
| OOOO O OOO |
110000 ++ O OO O O |
105000 +O O O O O O O OO OOO O O O OO |
O OO O O OO O O O O O O O O |
100000 ++ |
95000 ++ |
| |
90000 ++ |
85000 ++ |
| |
80000 ++ |
75000 ++ |
| *.***.* **. .***.****.***. |
70000 **-***-------*---***-****-***--------------****-***-****-***-****-**
time.involuntary_context_switches
115000 ++-----------------------------------------------------------------+
| OOOO O OOO |
110000 ++ O OO O O |
105000 +O O O O O O O OO OOO O O O OO |
O OO O O OO O O O O O O O O |
100000 ++ |
95000 ++ |
| |
90000 ++ |
85000 ++ |
| |
80000 ++ |
75000 ++ |
| *.***.* **. .***.****.***. |
70000 **-***-------*---***-****-***--------------****-***-****-***-****-**
vmstat.system.in
1500 ++-------------------------------------------------------------------+
| |
1400 ++ OOO OO OOO O |
1300 +O OO O O OO O OO OOO OOO OOOO O O OO |
O O OOO O O OO O |
1200 ++ OO |
| |
1100 ++ |
| |
1000 ++ |
900 ++ |
| |
800 ++.* .** .* .** * .* *. ** .* *. *. * |
** ** * ** *.* *.*** **.** * * * ** ***.* *.***.***.* |
700 ++---------------------------------------------------------------**-**
[*] 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 Ye.
5 years, 8 months
[lkp] [page_allocation] 0b444cfa6c: general protection fault: 0000 [#1]
by kernel test robot
FYI, we noticed the below changes on
git://bee.sh.intel.com/git/aaron/linux.git swap_numa
commit 0b444cfa6cd3db4a5954a010bc801ae6b01f464d ("page_allocation: do not clear page after allocation")
[ 0.305605] prandom: seed boundary self test passed
[ 0.307426] prandom: 100 self tests passed
[ 0.308834] NET: Registered protocol family 16
[ 0.323121] general protection fault: 0000 [#1]
[ 0.324166] CPU: 0 PID: 1 Comm: swapper Not tainted 4.5.0-rc4-00004-g0b444cf #1
[ 0.325749] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 0.327666] task: ffff880000058000 ti: ffff880000060000 task.ti: ffff880000060000
[ 0.329283] RIP: 0010:[<ffffffff815065af>] [<ffffffff815065af>] netlink_has_listeners+0x4d/0x62
[ 0.331202] RSP: 0000:ffff880000063da8 EFLAGS: 00010213
[ 0.332350] RAX: a623d84d3c6a2145 RBX: 0000000000000001 RCX: 0000000000000002
[ 0.333893] RDX: ffff880000155950 RSI: 0000000000000000 RDI: 0000000000000246
[ 0.335465] RBP: ffff880000063db8 R08: 0000000000000002 R09: 0000000000102017
[ 0.339964] R10: 0000000000000000 R11: ffff880000149154 R12: 0000000000000000
[ 0.341529] R13: 0000000000000000 R14: ffff8800000966e0 R15: 0000000000000000
[ 0.355889] FS: 0000000000000000(0000) GS:ffffffff81927000(0000) knlGS:0000000000000000
[ 0.357645] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 0.358888] CR2: 00000000ffffffff CR3: 0000000001916000 CR4: 00000000000406f0
[ 0.360444] Stack:
[ 0.360884] ffff880000169848 ffff880000149000 ffff880000063e10 ffffffff81205a2e
[ 0.362571] 0000000000000000 ffff88000016d800 ffffffff818ca011 ffff880000092120
[ 0.364259] ffff880000169848 0000000000000000 0000000000000000 ffffffff81ab60b0
[ 0.365930] Call Trace:
[ 0.366489] [<ffffffff81205a2e>] kobject_uevent_env+0x20a/0x35d
[ 0.367790] [<ffffffff81205b8c>] kobject_uevent+0xb/0xd
[ 0.368942] [<ffffffff81205054>] kset_register+0x37/0x3f
[ 0.382601] [<ffffffff812c63ad>] __class_register+0xe0/0x175
[ 0.383854] [<ffffffff81a5794f>] ? gpiolib_debugfs_init+0x24/0x24
[ 0.385206] [<ffffffff81a57969>] gpiolib_sysfs_init+0x1a/0x93
[ 0.386480] [<ffffffff81a5794f>] ? gpiolib_debugfs_init+0x24/0x24
[ 0.387821] [<ffffffff81a35ede>] do_one_initcall+0xe0/0x165
[ 0.389047] [<ffffffff81a360e8>] kernel_init_freeable+0x185/0x20a
[ 0.390413] [<ffffffff815b3513>] ? rest_init+0xba/0xba
[ 0.391547] [<ffffffff815b351c>] kernel_init+0x9/0xca
[ 0.392668] [<ffffffff815b8a3f>] ret_from_fork+0x3f/0x70
[ 0.393834] [<ffffffff815b3513>] ? rest_init+0xba/0xba
[ 0.394968] Code: 02 00 00 45 31 e4 48 69 d2 b0 01 00 00 48 03 15 a8 cf 52 00 48 8b 82 70 01 00 00 48 85 c0 74 13 8d 73 ff 3b b2 7c 01 00 00 73 08 <48> 0f a3 70 10 45 19 e4 e8 a7 e8 ff ff 44 89 e0 5b 41 5c 5d c3
[ 0.413066] RIP [<ffffffff815065af>] netlink_has_listeners+0x4d/0x62
[ 0.414531] RSP <ffff880000063da8>
[ 0.415418] ---[ end trace 5787594098d00646 ]---
[ 0.416426] Kernel panic - not syncing: Fatal exception
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-randconfig-s4-03181734/gcc-5/0b444cfa6cd3db4a5954a010bc801ae6b01f464d/vmlinuz-4.5.0-rc4-00004-g0b444cf -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-1G-3/bisect_boot-1-debian-x86_64-2015-02-07.cgz-x86_64-randconfig-s4-03181734-0b444cfa6cd3db4a5954a010bc801ae6b01f464d-20160318-34923-138plvt-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s4-03181734 branch=linux-devel/devel-catchup-201603181757 commit=0b444cfa6cd3db4a5954a010bc801ae6b01f464d BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-03181734/gcc-5/0b444cfa6cd3db4a5954a010bc801ae6b01f464d/vmlinuz-4.5.0-rc4-00004-g0b444cf max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-1G/debian-x86_64-2015-02-07.cgz/x86_64-randconfig-s4-03181734/gcc-5/0b444cfa6cd3db4a5954a010bc801ae6b01f464d/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-3::dhcp' -initrd /fs/sdd1/initrd-vm-kbuild-1G-3 -m 1024 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23002-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -device virtio-scsi-pci,id=scsi0 -drive file=/fs/sdd1/disk0-vm-kbuild-1G-3,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/sdd1/disk1-vm-kbuild-1G-3,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/sdd1/disk2-vm-kbuild-1G-3,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/sdd1/disk3-vm-kbuild-1G-3,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/sdd1/disk4-vm-kbuild-1G-3,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-3 -serial file:/dev/shm/kboot/serial-vm-kbuild-1G-3 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
5 years, 8 months
[lkp] [split out nsroot printing to its own fn] e2469d8a21: BUG: unable to handle kernel NULL pointer dereference at 0000000000000051
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/sergeh/linux-security 2016-03-18/nsroot
commit e2469d8a216a31ec4616553a3a2b250f86f524e9 ("split out nsroot printing to its own fn")
+------------------------------------------+------------+------------+
| | e2cdf823b9 | e2469d8a21 |
+------------------------------------------+------------+------------+
| boot_successes | 4 | 5 |
| boot_failures | 1 | 9 |
| invoked_oom-killer:gfp_mask=0x | 1 | |
| Mem-Info | 1 | |
| Out_of_memory:Kill_process | 1 | |
| backtrace:_do_fork | 1 | |
| backtrace:SyS_clone | 1 | |
| BUG:unable_to_handle_kernel | 0 | 9 |
| Oops | 0 | 9 |
| RIP:kernfs_path_from_node_locked | 0 | 9 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 9 |
| backtrace:vfs_read | 0 | 9 |
| backtrace:SyS_read | 0 | 9 |
+------------------------------------------+------------+------------+
[ 11.258126] systemd-sysv-generator[97]: Ignoring K01watchdog symlink in rc6.d, not generating watchdog.service.
[ 11.260270] systemd-fstab-generator[95]: Parsing /etc/fstab
[ 11.298349] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[ 11.964847] BUG: unable to handle kernel NULL pointer dereference at 0000000000000051
[ 11.968189] IP: [<ffffffff8126d8e7>] kernfs_path_from_node_locked+0x47/0x340
[ 11.970565] PGD 0
[ 11.971979] Oops: 0000 [#1] SMP
[ 11.973873] Modules linked in:
[ 11.975499] CPU: 1 PID: 1 Comm: systemd Not tainted 4.5.0-rc1-00010-ge2469d8 #3
[ 11.978546] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 11.981565] task: ffff880073420000 ti: ffff88007341c000 task.ti: ffff88007341c000
[ 11.984335] RIP: 0010:[<ffffffff8126d8e7>] [<ffffffff8126d8e7>] kernfs_path_from_node_locked+0x47/0x340
[ 11.987562] RSP: 0018:ffff88007341fcb8 EFLAGS: 00010002
[ 11.989266] RAX: 0000000000000001 RBX: ffff88007f4b5e88 RCX: 0000000000000000
[ 11.991282] RDX: 0000000000000000 RSI: ffff88007f85c880 RDI: 0000000000000001
[ 11.993447] RBP: ffff88007341fd00 R08: 0000000000000000 R09: ffffffff81bd599e
[ 11.995614] R10: 0000000000ffff0a R11: 0000000000000001 R12: ffff88007f4b5e88
[ 11.997783] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 11.999959] FS: 00007fbaf9802880(0000) GS:ffff880076900000(0000) knlGS:0000000000000000
[ 12.003088] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 12.005056] CR2: 0000000000000051 CR3: 000000007f517000 CR4: 00000000000406e0
[ 12.007079] Stack:
[ 12.008216] ffffffff81bd599e 0000000000000000 ffff88007f4ed000 002e2e2f00000000
[ 12.011405] ffff88007f4b5e88 0000000000000246 ffff88007f85c880 0000000000000000
[ 12.014596] 0000000000000000 ffff88007341fd38 ffffffff8126dc1e ffff88005a8c32a0
[ 12.017786] Call Trace:
[ 12.019020] [<ffffffff8126dc1e>] kernfs_path_from_node+0x3e/0x60
[ 12.020866] [<ffffffff811074de>] cgroup_show_options+0x10e/0x2b0
[ 12.037731] [<ffffffff8126cb02>] kernfs_sop_show_options+0x32/0x40
[ 12.039828] [<ffffffff81232d49>] show_mountinfo+0x1d9/0x270
[ 12.041688] [<ffffffff812109e7>] m_show+0x17/0x20
[ 12.043313] [<ffffffff81215d24>] seq_read+0x2c4/0x3a0
[ 12.045004] [<ffffffff811f0ed8>] __vfs_read+0x28/0xd0
[ 12.046693] [<ffffffff813acd43>] ? security_file_permission+0xa3/0xc0
[ 12.048614] [<ffffffff811f1627>] ? rw_verify_area+0x57/0xd0
[ 12.050385] [<ffffffff811f1726>] vfs_read+0x86/0x130
[ 12.052055] [<ffffffff811f2726>] SyS_read+0x46/0xa0
[ 12.053707] [<ffffffff818daa2e>] entry_SYSCALL_64_fastpath+0x12/0x6d
[ 12.055779] Code: 48 85 f6 48 89 55 c0 c7 45 d4 2f 2e 2e 00 0f 84 39 02 00 00 4c 39 e6 0f 84 4b 02 00 00 48 8b 7e 08 48 85 ff 48 89 f8 48 0f 44 c6 <48> 8b 50 50 49 8b 44 24 08 48 85 c0 48 89 c1 49 0f 44 cc 48 3b
[ 12.069654] RIP [<ffffffff8126d8e7>] kernfs_path_from_node_locked+0x47/0x340
[ 12.071979] RSP <ffff88007341fcb8>
[ 12.073503] CR2: 0000000000000051
[ 12.075007] ---[ end trace 357f81c3837373ff ]---
[ 12.076736] Kernel panic - not syncing: Fatal exception
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-rhel/gcc-4.9/e2469d8a216a31ec4616553a3a2b250f86f524e9/vmlinuz-4.5.0-rc1-00010-ge2469d8 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-2G-10/rand_will-it-scale-pwrite3-debian-x86_64-2015-02-07.cgz-x86_64-rhel-e2469d8a216a31ec4616553a3a2b250f86f524e9-20160320-65737-119hv5w-0.yaml ARCH=x86_64 kconfig=x86_64-rhel branch=sergeh-security/2016-03-18/nsroot commit=e2469d8a216a31ec4616553a3a2b250f86f524e9 BOOT_IMAGE=/pkg/linux/x86_64-rhel/gcc-4.9/e2469d8a216a31ec4616553a3a2b250f86f524e9/vmlinuz-4.5.0-rc1-00010-ge2469d8 max_uptime=1500 RESULT_ROOT=/result/will-it-scale/pwrite3/vm-kbuild-2G/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/e2469d8a216a31ec4616553a3a2b250f86f524e9/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-2G-10::dhcp' -initrd /fs/sda1/initrd-vm-kbuild-2G-10 -m 2048 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23025-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sda1/disk0-vm-kbuild-2G-10,media=disk,if=virtio -drive file=/fs/sda1/disk1-vm-kbuild-2G-10,media=disk,if=virtio -drive file=/fs/sda1/disk2-vm-kbuild-2G-10,media=disk,if=virtio -drive file=/fs/sda1/disk3-vm-kbuild-2G-10,media=disk,if=virtio -drive file=/fs/sda1/disk4-vm-kbuild-2G-10,media=disk,if=virtio -drive file=/fs/sda1/disk5-vm-kbuild-2G-10,media=disk,if=virtio -drive file=/fs/sda1/disk6-vm-kbuild-2G-10,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-2G-10 -serial file:/dev/shm/kboot/serial-vm-kbuild-2G-10 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
5 years, 8 months
[page_allocation] 0b444cfa6c: BUG: Bad page map in process init pte:02434063 pmd:0b2af067
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
aaron/swap_numa
commit 0b444cfa6cd3db4a5954a010bc801ae6b01f464d
Author: Aaron Lu <aaron.lu(a)intel.com>
AuthorDate: Fri Mar 18 17:07:31 2016 +0800
Commit: Aaron Lu <aaron.lu(a)intel.com>
CommitDate: Fri Mar 18 17:07:31 2016 +0800
page_allocation: do not clear page after allocation
+-----------------------------------------------------+------------+------------+------------+
| | 5a430f9c20 | 0b444cfa6c | d0d4199eb9 |
+-----------------------------------------------------+------------+------------+------------+
| boot_successes | 112 | 0 | 0 |
| boot_failures | 6 | 29 | 13 |
| BUG:unable_to_handle_kernel | 6 | 14 | 5 |
| Oops | 6 | 14 | 5 |
| EIP_is_at_perf_prepare_sample | 6 | | |
| Kernel_panic-not_syncing:Fatal_exception | 6 | 17 | 5 |
| backtrace:iterate_dir | 3 | | |
| backtrace:SyS_getdents64 | 3 | | |
| EIP_is_at_kmap_atomic_prot | 0 | 9 | 3 |
| backtrace:do_execve | 0 | 12 | 4 |
| backtrace:SyS_execve | 0 | 12 | 4 |
| BUG:Bad_page_map_in_process | 0 | 13 | 4 |
| BUG:Bad_page_state_in_process | 0 | 9 | 1 |
| invoked_oom-killer:gfp_mask=0x | 0 | 7 | 2 |
| Mem-Info | 0 | 7 | 2 |
| Out_of_memory:Kill_process | 0 | 7 | 2 |
| EIP_is_at_compound_head | 0 | 5 | 1 |
| BUG:Bad_rss-counter_state_mm:#idx:#val | 0 | 5 | |
| kernel_BUG_at_include/linux/mm.h | 0 | 3 | 1 |
| invalid_opcode:#[##] | 0 | 3 | 1 |
| invalid_opcode:#[##]DEBUG_PAGEALLOC_DEBUG_PAGEALLOC | 0 | 3 | 1 |
| EIP_is_at_put_page_testzero | 0 | 2 | 1 |
| backtrace:_do_fork | 0 | 3 | |
| backtrace:SyS_clone | 0 | 3 | |
| BUG:kernel_boot_hang | 0 | 8 | 6 |
| backtrace:do_group_exit | 0 | 1 | |
| backtrace:SyS_exit_group | 0 | 1 | |
| EIP_is_at_follow_page_mask | 0 | 1 | |
+-----------------------------------------------------+------------+------------+------------+
[ 4.808107] rc.local[97]: segfault at 1cb7 ip 77770a5c sp 7fd7daf0 error 6
[ 4.808107] rc.local[97]: segfault at 1cb7 ip 77770a5c sp 7fd7daf0 error 6 in ld-2.15.so[7776a000+20000] in ld-2.15.so[7776a000+20000]
[ 4.824273] BUG: Bad page map in process init pte:02434063 pmd:0b2af067
[ 4.824273] BUG: Bad page map in process init pte:02434063 pmd:0b2af067
Elapsed time: 20
qemu-system-x86_64 -enable-kvm -cpu kvm64 -kernel /pkg/linux/i386-randconfig-i1-201611/gcc-5/0b444cfa6cd3db4a5954a010bc801ae6b01f464d/vmlinuz-4.5.0-rc4-00004-g0b444cf -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 link=/kbuild-tests/run-queue/kvm/i386-randconfig-i1-201611/linux-devel:devel-catchup-201603181757:0b444cfa6cd3db4a5954a010bc801ae6b01f464d:bisect-linux-1/.vmlinuz-0b444cfa6cd3db4a5954a010bc801ae6b01f464d-20160318194046-27-intel12 branch=linux-devel/devel-catchup-201603181757 BOOT_IMAGE=/pkg/linux/i386-randconfig-i1-201611/gcc-5/0b444cfa6cd3db4a5954a010bc801ae6b01f464d/vmlinuz-4.5.0-rc4-00004-g0b444cf drbd.minor_count=8' -initrd /osimage/quantal/quantal-core-i386.cgz -m 300 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/KVM/disk0-quantal-intel12-32,media=disk,if=virtio -drive file=/fs/KVM/disk1-quantal-intel12-32,media=disk,if=virtio -drive file=/fs/KVM/disk2-quantal-intel12-32,media=disk,if=virtio -drive file=/fs/KVM/disk3-quantal-intel12-32,media=disk,if=virtio -drive file=/fs/KVM/disk4-quantal-intel12-32,media=disk,if=virtio -drive file=/fs/KVM/disk5-quantal-intel12-32,media=disk,if=virtio -drive file=/fs/KVM/disk6-quantal-intel12-32,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-quantal-intel12-32 -serial file:/dev/shm/kboot/serial-quantal-intel12-32 -daemonize -display none -monitor null
git bisect start d0d4199eb9a99adbe8f940b66e66ddcf8df205b1 b562e44f507e863c6792946e4e1b1449fbbac85d --
git bisect bad 11995d1888d0e33f154abc61ec2ecb92dc68024f # 18:45 5- 25 Merge 'xtensa/for_next' into devel-catchup-201603181757
git bisect good d690e919abe1fd78c8d922d72de5821f303836e5 # 18:50 29+ 2 Merge 'wsa/i2c/for-next' into devel-catchup-201603181757
git bisect bad 23ee57a1dd5a535208a7d4711e42f14095a3b947 # 19:00 7- 30 Merge 'aaron/swap_numa' into devel-catchup-201603181757
git bisect good d345b0a8c1a7abb236fd4e32850c8fcc98b53888 # 19:11 29+ 3 Merge 'kvalo-ath/ath-qca' into devel-catchup-201603181757
git bisect good c2efa9252cad76077151a98f9c65ddfe799ff09e # 19:25 29+ 1 swap_numa: add two events for swap allocation on local/remote node
git bisect bad 0b444cfa6cd3db4a5954a010bc801ae6b01f464d # 19:41 6- 16 page_allocation: do not clear page after allocation
git bisect good 5a430f9c20a4cb8b66e0f65b987f2564305a0b5c # 19:56 29+ 1 pmem: add 2 events for pmem local write and remote write
# first bad commit: [0b444cfa6cd3db4a5954a010bc801ae6b01f464d] page_allocation: do not clear page after allocation
git bisect good 5a430f9c20a4cb8b66e0f65b987f2564305a0b5c # 19:58 88+ 6 pmem: add 2 events for pmem local write and remote write
# extra tests with DEBUG_INFO
git bisect bad 0b444cfa6cd3db4a5954a010bc801ae6b01f464d # 20:16 32- 72 page_allocation: do not clear page after allocation
# extra tests on HEAD of linux-devel/devel-catchup-201603181757
git bisect bad d0d4199eb9a99adbe8f940b66e66ddcf8df205b1 # 20:16 0- 13 0day head guard for 'devel-catchup-201603181757'
# extra tests on tree/branch aaron/swap_numa
git bisect bad 0b444cfa6cd3db4a5954a010bc801ae6b01f464d # 20:17 0- 29 page_allocation: do not clear page after allocation
# extra tests with first bad commit reverted
git bisect good fb3c9d2bb8c7f0c36b19e6b6f0c72b70e4836446 # 20:29 89+ 3 Revert "page_allocation: do not clear page after allocation"
# extra tests on tree/branch linus/master
git bisect good 9dffdb38d864ae89e16ff7b3a09451270736e35b # 20:32 88+ 2 Merge tag 'staging-4.6-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/staging
# extra tests on tree/branch linux-next/master
git bisect good 5e3497cca281616e7930b74a0076b7324dcc2057 # 20:35 83+ 3 Add linux-next specific files for 20160318
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
5 years, 8 months
[lkp] [swap_numa] c55289c1a2: swapper/0: page allocation failure: order:0, mode:0x20200c2
by kernel test robot
FYI, we noticed the below changes on
git://bee.sh.intel.com/git/aaron/linux.git swap_numa_nodirectreclaim
commit c55289c1a217f9cfdb14ba79a524478423d04d61 ("swap_numa: no direct reclaim")
As below, the log "swapper/0: page allocation failure: order:0, mode:0x20200c2" showed with your commit.
[ 0.849161] PCI: CLS 0 bytes, default 64
[ 0.850071] Trying to unpack rootfs image as initramfs...
[ 0.850071] Trying to unpack rootfs image as initramfs...
[ 5.037416] swapper/0: page allocation failure: order:0, mode:0x20200c2
[ 5.037416] swapper/0: page allocation failure: order:0, mode:0x20200c2
[ 5.039155] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.5.0-rc4-00002-gc55289c #1
[ 5.039155] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.5.0-rc4-00002-gc55289c #1
[ 5.040634] 0000000000000000
[ 5.040634] 0000000000000000 ffff88002b86b8d0 ffff88002b86b8d0 ffffffff81333170 ffffffff81333170 00000000020200c2 00000000020200c2
[ 5.042151] 0000000000000000
[ 5.042151] 0000000000000000 ffff88002b86b958 ffff88002b86b958 ffffffff8111affe ffffffff8111affe 0000000000000040 0000000000000040
[ 5.043659] ffffffffffffffff
[ 5.043659] ffffffffffffffff ffffffff81ce91a0 ffffffff81ce91a0 00000001020200c2 00000001020200c2 000000002b86b910 000000002b86b910
[ 5.045173] Call Trace:
[ 5.045173] Call Trace:
[ 5.045665] [<ffffffff81333170>] dump_stack+0x79/0x99
[ 5.045665] [<ffffffff81333170>] dump_stack+0x79/0x99
[ 5.046684] [<ffffffff8111affe>] warn_alloc_failed+0xde/0x130
[ 5.046684] [<ffffffff8111affe>] warn_alloc_failed+0xde/0x130
[ 5.047841] [<ffffffff81035b39>] ? kvm_sched_clock_read+0x9/0x20
[ 5.047841] [<ffffffff81035b39>] ? kvm_sched_clock_read+0x9/0x20
[ 5.049087] [<ffffffff8111db94>] __alloc_pages_slowpath+0x1e4/0x820
[ 5.049087] [<ffffffff8111db94>] __alloc_pages_slowpath+0x1e4/0x820
[ 5.050600] [<ffffffff8111cfdc>] ? get_page_from_freelist+0xcc/0x9c0
[ 5.050600] [<ffffffff8111cfdc>] ? get_page_from_freelist+0xcc/0x9c0
[ 5.051875] [<ffffffff8111e3dd>] __alloc_pages_nodemask+0x20d/0x280
[ 5.051875] [<ffffffff8111e3dd>] __alloc_pages_nodemask+0x20d/0x280
[ 5.053139] [<ffffffff81116241>] pagecache_get_page+0xb1/0x260
[ 5.053139] [<ffffffff81116241>] pagecache_get_page+0xb1/0x260
[ 5.054323] [<ffffffff81116414>] grab_cache_page_write_begin+0x24/0x40
[ 5.054323] [<ffffffff81116414>] grab_cache_page_write_begin+0x24/0x40
[ 5.055628] [<ffffffff8118d584>] simple_write_begin+0x24/0x1f0
[ 5.055628] [<ffffffff8118d584>] simple_write_begin+0x24/0x1f0
[ 5.056802] [<ffffffff8111521d>] generic_perform_write+0xbd/0x1b0
[ 5.056802] [<ffffffff8111521d>] generic_perform_write+0xbd/0x1b0
[ 5.058034] [<ffffffff81117951>] ? generic_file_write_iter+0x31/0x1e0
[ 5.058034] [<ffffffff81117951>] ? generic_file_write_iter+0x31/0x1e0
[ 5.059351] [<ffffffff811178dd>] __generic_file_write_iter+0x19d/0x1e0
[ 5.059351] [<ffffffff811178dd>] __generic_file_write_iter+0x19d/0x1e0
[ 5.060673] [<ffffffff81117a03>] generic_file_write_iter+0xe3/0x1e0
[ 5.060673] [<ffffffff81117a03>] generic_file_write_iter+0xe3/0x1e0
[ 5.061932] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.061932] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.063046] [<ffffffff81165715>] __vfs_write+0xa5/0xf0
[ 5.063046] [<ffffffff81165715>] __vfs_write+0xa5/0xf0
[ 5.064088] [<ffffffff81165f19>] vfs_write+0xb9/0x1f0
[ 5.064088] [<ffffffff81165f19>] vfs_write+0xb9/0x1f0
[ 5.065138] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.065138] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.066250] [<ffffffff81166814>] SyS_write+0x44/0xa0
[ 5.066250] [<ffffffff81166814>] SyS_write+0x44/0xa0
[ 5.067254] [<ffffffff81edf681>] xwrite+0x29/0x5c
[ 5.067254] [<ffffffff81edf681>] xwrite+0x29/0x5c
[ 5.068214] [<ffffffff81edf73d>] do_copy+0x89/0xb6
[ 5.068214] [<ffffffff81edf73d>] do_copy+0x89/0xb6
[ 5.069196] [<ffffffff81edf32a>] write_buffer+0x23/0x34
[ 5.069196] [<ffffffff81edf32a>] write_buffer+0x23/0x34
[ 5.070252] [<ffffffff81edf366>] flush_buffer+0x2b/0x85
[ 5.070252] [<ffffffff81edf366>] flush_buffer+0x2b/0x85
[ 5.071317] [<ffffffff81efe769>] __gunzip+0x279/0x31d
[ 5.071317] [<ffffffff81efe769>] __gunzip+0x279/0x31d
[ 5.072366] [<ffffffff81efe4e6>] ? bunzip2+0x38f/0x38f
[ 5.072366] [<ffffffff81efe4e6>] ? bunzip2+0x38f/0x38f
[ 5.073401] [<ffffffff81edf33b>] ? write_buffer+0x34/0x34
[ 5.073401] [<ffffffff81edf33b>] ? write_buffer+0x34/0x34
[ 5.074506] [<ffffffff81edfe03>] ? clean_rootfs+0x14d/0x14d
[ 5.074506] [<ffffffff81edfe03>] ? clean_rootfs+0x14d/0x14d
[ 5.075625] [<ffffffff81efe81e>] gunzip+0x11/0x13
[ 5.075625] [<ffffffff81efe81e>] gunzip+0x11/0x13
[ 5.076576] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.076576] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.077692] [<ffffffff81edfbb6>] unpack_to_rootfs+0x13c/0x23c
[ 5.077692] [<ffffffff81edfbb6>] unpack_to_rootfs+0x13c/0x23c
[ 5.078850] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.078850] [<ffffffff81edf28c>] ? initrd_load+0x2cf/0x2cf
[ 5.079981] [<ffffffff81edfe5f>] populate_rootfs+0x5c/0xfc
[ 5.079981] [<ffffffff81edfe5f>] populate_rootfs+0x5c/0xfc
[ 5.081130] [<ffffffff810003d5>] do_one_initcall+0x85/0x1d0
[ 5.081130] [<ffffffff810003d5>] do_one_initcall+0x85/0x1d0
[ 5.082253] [<ffffffff81ede19a>] kernel_init_freeable+0x1a3/0x22d
[ 5.082253] [<ffffffff81ede19a>] kernel_init_freeable+0x1a3/0x22d
[ 5.083505] [<ffffffff81621ad0>] ? rest_init+0xc0/0xc0
[ 5.083505] [<ffffffff81621ad0>] ? rest_init+0xc0/0xc0
[ 5.084559] [<ffffffff81621ad9>] kernel_init+0x9/0xe0
[ 5.084559] [<ffffffff81621ad9>] kernel_init+0x9/0xe0
[ 5.085589] [<ffffffff8162a6cf>] ret_from_fork+0x3f/0x70
[ 5.085589] [<ffffffff8162a6cf>] ret_from_fork+0x3f/0x70
[ 5.086670] [<ffffffff81621ad0>] ? rest_init+0xc0/0xc0
[ 5.086670] [<ffffffff81621ad0>] ? rest_init+0xc0/0xc0
[ 5.087750] Mem-Info:
[ 5.087750] Mem-Info:
[ 5.088222] active_anon:0 inactive_anon:0 isolated_anon:0
[ 5.088222] active_file:18001 inactive_file:138722 isolated_file:0
[ 5.088222] unevictable:0 dirty:0 writeback:0 unstable:0
[ 5.088222] slab_reclaimable:7403 slab_unreclaimable:514
[ 5.088222] mapped:0 shmem:0 pagetables:0 bounce:0
[ 5.088222] free:8663 free_pcp:38 free_cma:0
[ 5.088222] active_anon:0 inactive_anon:0 isolated_anon:0
[ 5.088222] active_file:18001 inactive_file:138722 isolated_file:0
[ 5.088222] unevictable:0 dirty:0 writeback:0 unstable:0
[ 5.088222] slab_reclaimable:7403 slab_unreclaimable:514
[ 5.088222] mapped:0 shmem:0 pagetables:0 bounce:0
[ 5.088222] free:8663 free_pcp:38 free_cma:0
[ 5.094410] DMA free:792kB min:792kB low:988kB high:1188kB active_anon:0kB inactive_anon:0kB active_file:1648kB inactive_file:12664kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15992kB managed:15908kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:748kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[ 5.094410] DMA free:792kB min:792kB low:988kB high:1188kB active_anon:0kB inactive_anon:0kB active_file:1648kB inactive_file:12664kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15992kB managed:15908kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:748kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[ 5.102620] lowmem_reserve[]:
[ 5.102620] lowmem_reserve[]: 0 0 0 0 0 0 0 0
[ 5.103450] DMA32 free:33860kB min:33948kB low:42432kB high:50920kB active_anon:0kB inactive_anon:0kB active_file:70356kB inactive_file:542224kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:1032064kB managed:679024kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:28864kB slab_unreclaimable:2056kB kernel_stack:304kB pagetables:0kB unstable:0kB bounce:0kB free_pcp:152kB local_pcp:152kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[ 5.103450] DMA32 free:33860kB min:33948kB low:42432kB high:50920kB active_anon:0kB inactive_anon:0kB active_file:70356kB inactive_file:542224kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:1032064kB managed:679024kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:28864kB slab_unreclaimable:2056kB kernel_stack:304kB pagetables:0kB unstable:0kB bounce:0kB free_pcp:152kB local_pcp:152kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[ 5.112067] lowmem_reserve[]:
[ 5.112067] lowmem_reserve[]: 0 0 0 0 0 0 0 0
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu host -kernel /pkg/linux/x86_64-randconfig-s1-03170437/gcc-5/c55289c1a217f9cfdb14ba79a524478423d04d61/vmlinuz-4.5.0-rc4-00002-gc55289c -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-1G-2/bisect_autotest-fsfuzzer-debian-x86_64-2015-02-07.cgz-x86_64-randconfig-s1-03170437-c55289c1a217f9cfdb14ba79a524478423d04d61-20160317-30446-1fu7ssn-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s1-03170437 branch=linux-devel/devel-spot-201603170344 commit=c55289c1a217f9cfdb14ba79a524478423d04d61 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s1-03170437/gcc-5/c55289c1a217f9cfdb14ba79a524478423d04d61/vmlinuz-4.5.0-rc4-00002-gc55289c max_uptime=3600 RESULT_ROOT=/result/autotest/fsfuzzer/vm-lkp-wsx03-1G/debian-x86_64-2015-02-07.cgz/x86_64-randconfig-s1-03170437/gcc-5/c55289c1a217f9cfdb14ba79a524478423d04d61/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-lkp-wsx03-1G-2::dhcp' -initrd /fs/sdc1/initrd-vm-lkp-wsx03-1G-2 -m 1024 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23601-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdc1/disk0-vm-lkp-wsx03-1G-2,media=disk,if=virtio -drive file=/fs/sdc1/disk1-vm-lkp-wsx03-1G-2,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-1G-2 -serial file:/dev/shm/kboot/serial-vm-lkp-wsx03-1G-2 -daemonize -display none -monitor null
Thanks,
Xiaolong Ye
5 years, 8 months
[lkp] [of] e9fb34ab2b: INFO: possible recursive locking detected ]
by kernel test robot
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/geert/renesas-drivers.git topic/renesas-overlays
commit e9fb34ab2bd1aafaa9da3e3c0511faddb0a7542b ("of: unittest: changeset helpers")
[ 4.316732] /testcase-data/phandle-tests/consumer-a: arguments longer than property
[ 4.318017]
[ 4.318249] =============================================
[ 4.318979] [ INFO: possible recursive locking detected ]
[ 4.319710] 4.5.0-00021-ge9fb34a #1 Not tainted
[ 4.320327] ---------------------------------------------
[ 4.321052] swapper/1 is trying to acquire lock:
[ 4.321682] (of_mutex){+.+.+.}, at: [<ffffffff81f4c276>] of_changeset_apply+0x16/0x40
[ 4.322825]
[ 4.322825] but task is already holding lock:
[ 4.323611] (of_mutex){+.+.+.}, at: [<ffffffff835532d2>] of_unittest+0x1ddd/0x268f
[ 4.324714]
[ 4.324714] other info that might help us debug this:
[ 4.325598] Possible unsafe locking scenario:
[ 4.325598]
[ 4.326401] CPU0
[ 4.326739] ----
[ 4.327086] lock(of_mutex);
[ 4.327528] lock(of_mutex);
[ 4.327969]
[ 4.327969] *** DEADLOCK ***
[ 4.327969]
[ 4.328770] May be due to missing lock nesting notation
[ 4.328770]
[ 4.331776] 1 lock held by swapper/1:
[ 4.332286] #0: (of_mutex){+.+.+.}, at: [<ffffffff835532d2>] of_unittest+0x1ddd/0x268f
[ 4.333468]
[ 4.333468] stack backtrace:
[ 4.334065] CPU: 0 PID: 1 Comm: swapper Not tainted 4.5.0-00021-ge9fb34a #1
[ 4.334998] ffff880013450040 ffff880013457b10 ffffffff81781e14 ffff880013457c20
[ 4.336068] ffffffff81103a30 ffff880013457c30 0000000000000046 ffff880013457c40
[ 4.337141] 0000000000000046 ffff88001022f8a0 ffffffff83628346 ffffffff83628343
[ 4.338208] Call Trace:
[ 4.338550] [<ffffffff81781e14>] dump_stack+0x19/0x25
[ 4.339248] [<ffffffff81103a30>] validate_chain+0x1190/0x1970
[ 4.340126] [<ffffffff81109156>] __lock_acquire+0x556/0x1860
[ 4.340899] [<ffffffff81109187>] ? __lock_acquire+0x587/0x1860
[ 4.341702] [<ffffffff81f4c276>] ? of_changeset_apply+0x16/0x40
[ 4.342518] [<ffffffff8110b65a>] lock_acquire+0xaa/0x180
[ 4.343252] [<ffffffff81f4c276>] ? of_changeset_apply+0x16/0x40
[ 4.344116] [<ffffffff820e475e>] mutex_lock_nested+0x6e/0x460
[ 4.344903] [<ffffffff81f4c276>] ? of_changeset_apply+0x16/0x40
[ 4.345718] [<ffffffff835532d2>] ? of_unittest+0x1ddd/0x268f
[ 4.346498] [<ffffffff81f4c5ff>] ? of_changeset_add_property_copy+0x8f/0x100
[ 4.347458] [<ffffffff81f4c276>] of_changeset_apply+0x16/0x40
[ 4.348329] [<ffffffff835532db>] of_unittest+0x1de6/0x268f
[ 4.349092] [<ffffffff811fe816>] ? kfree+0x156/0x290
[ 4.349777] [<ffffffff8110582d>] ? trace_hardirqs_on+0xd/0x10
[ 4.350566] [<ffffffff835514f5>] ? of_unittest_overlay+0x1e6c/0x1e6c
[ 4.351438] [<ffffffff834f77cb>] do_one_initcall+0x136/0x1cb
[ 4.352219] [<ffffffff834f77cb>] ? do_one_initcall+0x136/0x1cb
[ 4.353013] [<ffffffff834f7a91>] kernel_init_freeable+0x231/0x2c7
[ 4.353852] [<ffffffff820d7ec0>] ? rest_init+0x170/0x170
[ 4.354586] [<ffffffff820d7ec9>] kernel_init+0x9/0xd0
[ 4.355288] [<ffffffff820e9e1f>] ret_from_fork+0x3f/0x70
[ 4.356014] [<ffffffff820d7ec0>] ? rest_init+0x170/0x170
[ 4.404482] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[ 61.130268] Writes: Total: 2 Max/Min: 0/0 Fail: 0
[ 121.130263] Writes: Total: 2 Max/Min: 0/0 Fail: 0
[ 181.130266] Writes: Total: 2 Max/Min: 0/0 Fail: 0
[ 240.365270] INFO: task swapper:1 blocked for more than 120 seconds.
[ 240.367221] Not tainted 4.5.0-00021-ge9fb34a #1
[ 240.368051] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 240.369327] swapper D ffff880013457cf8 0 1 0 0x00000000
[ 240.370521] ffff880013457cf8 ffff8800105da4c0 ffff880013454000 ffff880013454008
[ 240.371803] ffff880013450040 00000000ffffffff ffffffff81f4c276 ffff880013457d18
[ 240.373087] ffffffff820e2790 ffffffff833bc360 0000000000000246 ffff880013457d28
[ 240.374386] Call Trace:
[ 240.374795] [<ffffffff81f4c276>] ? of_changeset_apply+0x16/0x40
[ 240.375770] [<ffffffff820e2790>] schedule+0x40/0x100
[ 240.376604] [<ffffffff820e285f>] schedule_preempt_disabled+0xf/0x20
[ 240.377634] [<ffffffff820e4878>] mutex_lock_nested+0x188/0x460
[ 240.378621] [<ffffffff81f4c276>] ? of_changeset_apply+0x16/0x40
[ 240.379595] [<ffffffff81f4c5ff>] ? of_changeset_add_property_copy+0x8f/0x100
[ 240.380718] [<ffffffff81f4c276>] of_changeset_apply+0x16/0x40
[ 240.381660] [<ffffffff835532db>] of_unittest+0x1de6/0x268f
[ 240.382570] [<ffffffff811fe816>] ? kfree+0x156/0x290
[ 240.383380] [<ffffffff8110582d>] ? trace_hardirqs_on+0xd/0x10
[ 240.384333] [<ffffffff835514f5>] ? of_unittest_overlay+0x1e6c/0x1e6c
[ 240.385380] [<ffffffff834f77cb>] do_one_initcall+0x136/0x1cb
[ 240.386175] [<ffffffff834f77cb>] ? do_one_initcall+0x136/0x1cb
[ 240.386990] [<ffffffff834f7a91>] kernel_init_freeable+0x231/0x2c7
[ 240.387855] [<ffffffff820d7ec0>] ? rest_init+0x170/0x170
[ 240.388612] [<ffffffff820d7ec9>] kernel_init+0x9/0xd0
[ 240.389339] [<ffffffff820e9e1f>] ret_from_fork+0x3f/0x70
[ 240.390079] [<ffffffff820d7ec0>] ? rest_init+0x170/0x170
[ 240.390826] INFO: lockdep is turned off.
[ 240.391437] Sending NMI to all CPUs:
[ 240.392072] NMI backtrace for cpu 0
[ 240.392605] CPU: 0 PID: 9 Comm: khungtaskd Not tainted 4.5.0-00021-ge9fb34a #1
[ 240.393671] task: ffff880013548240 ti: ffff88001354c000 task.ti: ffff88001354c000
[ 240.394777] RIP: 0010:[<ffffffff8105d492>] [<ffffffff8105d492>] flat_send_IPI_mask+0x82/0xf0
[ 240.396029] RSP: 0000:ffff88001354fd40 EFLAGS: 00000046
[ 240.396814] RAX: 0000000001000000 RBX: 0000000000000c00 RCX: 0000000000000000
[ 240.397864] RDX: 0000000000000c00 RSI: 0000000000000002 RDI: 0000000000000300
[ 240.398908] RBP: ffff88001354fd68 R08: 0000000000000000 R09: 0000000000000000
[ 240.399956] R10: ffff880013450320 R11: 0000000000000001 R12: 0000000000000282
[ 240.401006] R13: 0000000000000001 R14: 0000000000000002 R15: ffffffff825ec560
[ 240.402081] FS: 0000000000000000(0000) GS:ffffffff82599000(0000) knlGS:0000000000000000
[ 240.403266] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 240.404135] CR2: 0000000000000000 CR3: 0000000002570000 CR4: 00000000000006f0
[ 240.405287] Stack:
[ 240.405617] ffffffff825ec560 ffffffff834f2b20 ffffffff81055de0 ffff8800134501c0
[ 240.406751] 00000000000003ff ffff88001354fd88 ffffffff81055e04 0000000000000001
[ 240.407924] ffffffff82d4f580 ffff88001354fdc8 ffffffff817890d8 0000000000000001
[ 240.409110] Call Trace:
[ 240.409520] [<ffffffff81055de0>] ? arch_trigger_all_cpu_backtrace_handler+0x20/0x20
[ 240.410749] [<ffffffff81055e04>] nmi_raise_cpu_backtrace+0x24/0x40
[ 240.411753] [<ffffffff817890d8>] nmi_trigger_all_cpu_backtrace+0x208/0x390
[ 240.412858] [<ffffffff81055e34>] arch_trigger_all_cpu_backtrace+0x14/0x20
[ 240.413955] [<ffffffff8115b439>] watchdog+0x389/0x600
[ 240.414781] [<ffffffff8115b12c>] ? watchdog+0x7c/0x600
[ 240.415623] [<ffffffff8115b0b0>] ? hung_task_panic+0x20/0x20
[ 240.416542] [<ffffffff810e5ce0>] kthread+0x130/0x1a0
[ 240.417355] [<ffffffff810e5bb0>] ? __kthread_parkme+0x160/0x160
[ 240.418319] [<ffffffff820e9e1f>] ret_from_fork+0x3f/0x70
[ 240.419173] [<ffffffff810e5bb0>] ? __kthread_parkme+0x160/0x160
[ 240.420131] Code: c3 5f ff 80 e6 10 75 f2 44 89 e8 c1 e0 18 89 04 25 10 c3 5f ff 44 89 f2 09 da 80 cf 04 41 83 fe 02 0f 44 d3 89 14 25 00 c3 5f ff <41> f7 c4 00 02 00 00 74 1a e8 80 83 0a 00 4c 89 e7 57 9d 66 66
[ 240.423052] Kernel panic - not syncing: hung_task: blocked tasks
[ 240.424004] CPU: 0 PID: 9 Comm: khungtaskd Not tainted 4.5.0-00021-ge9fb34a #1
[ 240.425142] ffff880013450040 ffff88001354fd58 ffffffff81781e14 ffff88001354fdd8
[ 240.426383] ffffffff81189382 0000000000000008 ffff88001354fde8 ffff88001354fd80
[ 240.427624] ffff880013450320 0000000000000001 00000000000000c8 000000000000075d
[ 240.428861] Call Trace:
[ 240.429267] [<ffffffff81781e14>] dump_stack+0x19/0x25
[ 240.430089] [<ffffffff81189382>] panic+0xc7/0x2e6
[ 240.430850] [<ffffffff8115b445>] watchdog+0x395/0x600
[ 240.431663] [<ffffffff8115b12c>] ? watchdog+0x7c/0x600
[ 240.432486] [<ffffffff8115b0b0>] ? hung_task_panic+0x20/0x20
[ 240.433395] [<ffffffff810e5ce0>] kthread+0x130/0x1a0
[ 240.434200] [<ffffffff810e5bb0>] ? __kthread_parkme+0x160/0x160
[ 240.435150] [<ffffffff820e9e1f>] ret_from_fork+0x3f/0x70
[ 240.435998] [<ffffffff810e5bb0>] ? __kthread_parkme+0x160/0x160
[ 240.436949] Kernel Offset: disabled
Elapsed time: 250
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Westmere -kernel /pkg/linux/x86_64-randconfig-v0-03180107/gcc-5/e9fb34ab2bd1aafaa9da3e3c0511faddb0a7542b/vmlinuz-4.5.0-00021-ge9fb34a -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-ia32-27/bisect_boot-1-yocto-minimal-i386.cgz-x86_64-randconfig-v0-03180107-e9fb34ab2bd1aafaa9da3e3c0511faddb0a7542b-20160318-82431-5l5wq9-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-v0-03180107 branch=linux-devel/devel-spot-201603180038 commit=e9fb34ab2bd1aafaa9da3e3c0511faddb0a7542b BOOT_IMAGE=/pkg/linux/x86_64-randconfig-v0-03180107/gcc-5/e9fb34ab2bd1aafaa9da3e3c0511faddb0a7542b/vmlinuz-4.5.0-00021-ge9fb34a max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-ia32/yocto-minimal-i386.cgz/x86_64-randconfig-v0-03180107/gcc-5/e9fb34ab2bd1aafaa9da3e3c0511faddb0a7542b/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-yocto-ia32-27::dhcp drbd.minor_count=8' -initrd /fs/sde1/initrd-vm-kbuild-yocto-ia32-27 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sde1/disk0-vm-kbuild-yocto-ia32-27,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-ia32-27 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-ia32-27 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
5 years, 8 months
[lkp] [of] 854fc7d606: kmsg.dt-test###FAIL_of_unittest_parse_phandle_with_args():#of_count_phandle_with_args()returned-#, expected
by kernel test robot
FYI, we noticed the below changes on
https://github.com/0day-ci/linux Joerg-Roedel/of-Implement-iterator-for-phandles/20160317-004643
commit 854fc7d60637a7eac7bb1b43789c7901bfa70cff ("of: Implement iterator for phandles")
As below, the log "### dt-test ### FAIL of_unittest_parse_phandle_with_args():300 of_count_phandle_with_args() returned -2, expected 7"
showed with your commit.
[ 1.955220] Loading compiled-in X.509 certificates
[ 1.957426] Key type encrypted registered
[ 1.959408] hctosys: unable to open rtc device (rtc0)
[ 1.961927] device-tree: Duplicate name in testcase-data, renamed to "duplicate-name#1"
[ 1.966130] ### dt-test ### start of unittest - you will see error messages
[ 1.968391] ### dt-test ### FAIL of_unittest_parse_phandle_with_args():300 of_count_phandle_with_args() returned -2, expected 7
[ 1.990895] /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[ 1.994720] /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[ 1.998549] /testcase-data/phandle-tests/consumer-a: could not find phandle
[ 2.000608] /testcase-data/phandle-tests/consumer-a: could not find phandle
[ 2.002677] /testcase-data/phandle-tests/consumer-a: arguments longer than property
[ 2.005500] /testcase-data/phandle-tests/consumer-a: arguments longer than property
[ 2.007943] irq: no irq domain found for /testcase-data/interrupts/intc0 !
[ 2.010375] ### dt-test ### end of unittest - 109 passed, 1 failed
[ 2.014771] Freeing unused kernel memory: 1308K (ffffffff919d9000 - ffffffff91b20000)
[ 2.017857] Write protecting the kernel read-only data: 14336k
[ 2.020328] Freeing unused kernel memory: 400K (ffff880010f9c000 - ffff880011000000)
[ 2.028257] Freeing unused kernel memory: 568K (ffff880011372000 - ffff880011400000)
[ 2.536404] tsc: Refined TSC clocksource calibration: 2693.510 MHz
[ 2.539155] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34b60feb, max_idle_ns: 440795225049 ns
[ 2.553801] gfs2: path_lookup on rootfs returned error -2
Thanks,
Xiaolong Ye
5 years, 8 months
Re: [LKP] [lkp] [drm/i915] 396e33ae20: [drm:intel_set_cpu_fifo_underrun_reporting [i915]] *ERROR* uncleared fifo underrun on pipe B
by Maarten Lankhorst
Op 17-03-16 om 11:49 schreef William Dauchy:
> On Mon, Jan 11, 2016 at 2:35 AM, kernel test robot
> <ying.huang(a)linux.intel.com> wrote:
>> FYI, we noticed the below changes on
>>
>> git://anongit.freedesktop.org/drm-intel drm-intel-next-queued
>> commit 396e33ae204f52abebec9e578f44c749305500f4 ("drm/i915: Add two-stage ILK-style watermark programming (v10)")
>>
>>
>> +--------------------------------------------------+------------+------------+
>> | | 2dfb0b816d | 396e33ae20 |
>> +--------------------------------------------------+------------+------------+
>> | boot_successes | 8 | 19 |
>> | boot_failures | 0 | 4 |
>> | drm:intel_set_cpu_fifo_underrun_reporting[i915]] | 0 | 4 |
>> | drm:intel_cpu_fifo_underrun_irq_handler[i915]] | 0 | 4 |
>> | drm:intel_set_pch_fifo_underrun_reporting[i915]] | 0 | 4 |
>> | drm:intel_pch_fifo_underrun_irq_handler[i915]] | 0 | 4 |
>> +--------------------------------------------------+------------+------------+
>>
>>
>>
>> [ 36.077826] NFS: Registering the id_resolver key type
>> [ 36.078178] Key type id_resolver registered
>> [ 36.078444] Key type id_legacy registered
>> [ 60.884026] [drm:intel_set_cpu_fifo_underrun_reporting [i915]] *ERROR* uncleared fifo underrun on pipe B
>> [ 60.884659] [drm:intel_cpu_fifo_underrun_irq_handler [i915]] *ERROR* CPU pipe B FIFO underrun
>> [ 60.885221] [drm:intel_set_pch_fifo_underrun_reporting [i915]] *ERROR* uncleared pch fifo underrun on pch transcoder B
>> [ 60.885887] [drm:intel_pch_fifo_underrun_irq_handler [i915]] *ERROR* PCH transcoder B FIFO underrun
>> [ 61.180651] random: nonblocking pool is initialized
>> [ 81.430503] [drm:intel_set_cpu_fifo_underrun_reporting [i915]] *ERROR* uncleared fifo underrun on pipe C
>> [ 81.431193] [drm:intel_cpu_fifo_underrun_irq_handler [i915]] *ERROR* CPU pipe C FIFO underrun
>> [ 81.431782] [drm:intel_set_pch_fifo_underrun_reporting [i915]] *ERROR* uncleared pch fifo underrun on pch transcoder C
>> [ 81.432474] [drm:intel_pch_fifo_underrun_irq_handler [i915]] *ERROR* PCH transcoder C FIFO underrun
>>
>>
>> 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
> I am still triggering these errors on v4.4.6
> Does anybody knows if there is a fix in queue? What can I do to help
> debug this issue?
>
Does it happen with latest -nightly?
~Maarten
5 years, 8 months