June 2019 - LKP - Ml01.01.Org

[btrfs] 6b50831aa2: WARNING:at_fs/btrfs/extent-tree.c:#btrfs_free_reserved_data_space_noquota[btrfs]

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: 6b50831aa27e39e73086c1bc950bb57a9c9d4c0d ("btrfs: Add a simple buffered iomap write") https://github.com/goldwynr/linux btrfs-iomap in testcase: ltp with following parameters: disk: 1HDD fs: xfs test: syscalls_part2 test-description: The LTP testsuite contains a collection of tools for testing the Linux kernel and related features. test-url: http://linux-test-project.github.io/ on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 8G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +----------------------------------------------------------------------------------+------------+------------+ | | ab90a1f624 | 6b50831aa2 | +----------------------------------------------------------------------------------+------------+------------+ | boot_successes | 37 | 0 | | boot_failures | 33 | 68 | | BUG:kernel_reboot-without-warning_in_test_stage | 20 | 2 | | BUG:kernel_hang_in_boot_stage | 13 | 14 | | WARNING:at_fs/btrfs/extent-tree.c:#btrfs_free_reserved_data_space_noquota[btrfs] | 0 | 52 | | RIP:btrfs_free_reserved_data_space_noquota[btrfs] | 0 | 52 | | WARNING:at_fs/btrfs/inode.c:#btrfs_destroy_inode[btrfs] | 0 | 46 | | RIP:btrfs_destroy_inode[btrfs] | 0 | 46 | | WARNING:at_fs/btrfs/extent-tree.c:#update_bytes_may_use[btrfs] | 0 | 10 | | RIP:update_bytes_may_use[btrfs] | 0 | 10 | | WARNING:at_fs/btrfs/extent-tree.c:#btrfs_free_block_groups[btrfs] | 0 | 46 | | RIP:btrfs_free_block_groups[btrfs] | 0 | 46 | +----------------------------------------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <lkp(a)intel.com> [ 752.577642] WARNING: CPU: 1 PID: 5590 at fs/btrfs/extent-tree.c:69 btrfs_free_reserved_data_space_noquota+0xf7/0x110 [btrfs] [ 752.591998] Modules linked in: btrfs xor zstd_decompress zstd_compress raid6_pq ext4 mbcache jbd2 tun loop xfs libcrc32c dm_mod bochs_drm ttm sr_mod cdrom sg crct10dif_pclmul drm_kms_helper crc32_pclmul ata_generic crc32c_intel pata_acpi ghash_clmulni_intel syscopyarea sysfillrect sysimgblt ppdev fb_sys_fops snd_pcm drm snd_timer snd ata_piix aesni_intel crypto_simd cryptd glue_helper soundcore libata joydev pcspkr serio_raw floppy i2c_piix4 parport_pc parport ip_tables [ 752.610424] ioctl01 5 TPASS : failed as expected: TEST_ERRNO=EFAULT(14): Bad address [ 752.610435] [ 752.622071] CPU: 1 PID: 5590 Comm: ioctl08 Not tainted 5.2.0-rc5-00004-g6b50831 #1 [ 752.622075] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 752.622203] RIP: 0010:btrfs_free_reserved_data_space_noquota+0xf7/0x110 [btrfs] [ 752.622210] Code: ee e8 9d ba 60 c9 49 8b 04 24 48 85 c0 75 da 65 ff 0d a5 19 82 3f e9 79 ff ff ff 48 89 c1 48 f7 d9 48 39 ca 0f 83 5e ff ff ff <0f> 0b 48 c7 43 28 00 00 00 00 e9 56 ff ff ff 66 2e 0f 1f 84 00 00 [ 752.642700] ioctl01_02 1 TPASS : ioctl01 Passed with /dev/tty59 [ 752.642711] [ 752.645239] RSP: 0018:ffff9f53c5413768 EFLAGS: 00010287 [ 752.645246] RAX: fffffffffffff000 RBX: ffff8e2fd8843e00 RCX: 0000000000001000 [ 752.645247] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff8e2fd8843e00 [ 752.645249] RBP: 0000000000000fff R08: 0000000000004000 R09: ffff8e2fd8390000 [ 752.645250] R10: ffff8e2fd834d800 R11: ffff9f53c5413a44 R12: ffff8e301027dc80 [ 752.645252] R13: ffff8e2fd8390000 R14: ffff8e2fd834d800 R15: ffff9f53c5413864 [ 752.645255] FS: 00007f975bf78500(0000) GS:ffff8e307fd00000(0000) knlGS:0000000000000000 [ 752.645257] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 752.645259] CR2: 00007f975ba996e0 CR3: 0000000198178000 CR4: 00000000000006e0 [ 752.645270] Call Trace: [ 752.645402] btrfs_clear_delalloc_extent+0x1a1/0x420 [btrfs] [ 752.645415] ? _cond_resched+0x19/0x30 [ 752.667733] [ 752.672014] clear_state_bit+0x75/0x1b0 [btrfs] [ 752.672058] __clear_extent_bit+0x222/0x440 [btrfs] [ 752.688932] ioctl01_02 0 TINFO : Testing ioctl01 with /dev/tty6 [ 752.688945] [ 752.693246] extent_clear_unlock_delalloc+0x43/0x70 [btrfs] [ 752.693284] cow_file_range+0x37b/0x430 [btrfs] [ 752.726049] ioctl01 1 TPASS : failed as expected: TEST_ERRNO=EBADF(9): Bad file descriptor [ 752.726057] [ 752.728410] btrfs_run_delalloc_range+0xe4/0x4b0 [btrfs] [ 752.728451] ? find_lock_delalloc_range+0x1f0/0x220 [btrfs] [ 752.755397] ioctl01 2 TPASS : failed as expected: TEST_ERRNO=EFAULT(14): Bad address [ 752.755407] [ 752.756101] writepage_delalloc+0x104/0x150 [btrfs] [ 752.779612] ioctl01 3 TPASS : failed as expected: TEST_ERRNO=ENOTTY(25): Inappropriate ioctl for device [ 752.779622] [ 752.781927] __extent_writepage+0x190/0x320 [btrfs] [ 752.781954] extent_write_cache_pages+0x240/0x400 [btrfs] [ 752.806258] ? __set_extent_bit+0x4f7/0x630 [btrfs] [ 752.806742] ioctl01 4 TPASS : failed as expected: TEST_ERRNO=ENOTTY(25): Inappropriate ioctl for device [ 752.806748] [ 752.808586] ? block_rsv_release_bytes+0x33/0x160 [btrfs] [ 752.808616] extent_writepages+0x52/0xa0 [btrfs] [ 752.823726] do_writepages+0x4b/0xf0 [ 752.829269] ? __filemap_fdatawrite_range+0xc1/0x100 [ 752.831284] ioctl01 5 TPASS : failed as expected: TEST_ERRNO=EFAULT(14): Bad address [ 752.831293] [ 752.832362] __filemap_fdatawrite_range+0xc1/0x100 [ 752.832445] btrfs_fdatawrite_range+0x1b/0x50 [btrfs] [ 752.845708] btrfs_wait_ordered_range+0x40/0x100 [btrfs] [ 752.849193] ? _cond_resched+0x19/0x30 [ 752.851096] btrfs_remap_file_range+0xfb/0x3e0 [btrfs] [ 752.852824] ioctl01_02 1 TPASS : ioctl01 Passed with /dev/tty6 [ 752.852832] [ 752.853308] vfs_dedupe_file_range_one+0x137/0x150 [ 752.853312] vfs_dedupe_file_range+0x159/0x1c0 [ 752.864461] do_vfs_ioctl+0x279/0x6f0 [ 752.866818] ksys_ioctl+0x70/0x80 [ 752.868194] [ 752.868998] __x64_sys_ioctl+0x16/0x20 [ 752.872968] do_syscall_64+0x5b/0x1e0 [ 752.875113] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 752.878196] RIP: 0033:0x7f975ba9f017 [ 752.880869] Code: 00 00 00 48 8b 05 81 7e 2b 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 51 7e 2b 00 f7 d8 64 89 01 48 [ 752.883534] ioctl01_02 0 TINFO : Testing ioctl01 with /dev/tty60 [ 752.883540] [ 752.887099] RSP: 002b:00007ffe85ae2798 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 [ 752.887102] RAX: ffffffffffffffda RBX: 000055917783a0b0 RCX: 00007f975ba9f017 [ 752.887103] RDX: 000055917783a0b0 RSI: 00000000c0189436 RDI: 0000000000000007 [ 752.887104] RBP: 0000559177256020 R08: 0000000000000008 R09: 000055917704d6b5 [ 752.887105] R10: 0000000000000370 R11: 0000000000000246 R12: 00007f975bf78498 [ 752.887106] R13: 0000000000000000 R14: 000055917704de10 R15: 0000000000000001 [ 752.887112] ---[ end trace f25bd001ff1f9a4e ]--- To reproduce: # build kernel cd linux cp config-5.2.0-rc5-00004-g6b50831 .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage git clone https://github.com/intel/lkp-tests.git cd lkp-tests find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email Thanks, lkp

11 months, 2 weeks

1
0
0 / 0

[mm] 815744d751: will-it-scale.per_process_ops 43.3% improvement

by kernel test robot

Greeting, FYI, we noticed a 43.3% improvement of will-it-scale.per_process_ops due to commit: commit: 815744d75152078cde5391fc1e3c2d4424323fb6 ("mm: memcontrol: don't batch updates of local VM stats and events") https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master in testcase: will-it-scale on test machine: 192 threads Intel(R) Xeon(R) CPU @ 2.20GHz with 192G memory with following parameters: nr_task: 100% mode: process test: page_fault3 cpufreq_governor: performance test-description: Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two. test-url: https://github.com/antonblanchard/will-it-scale Details are as below: --------------------------------------------------------------------------------------------------> To reproduce: git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp install job.yaml # job file is attached in this email bin/lkp run job.yaml ========================================================================================= compiler/cpufreq_governor/kconfig/mode/nr_task/rootfs/tbox_group/test/testcase: gcc-7/performance/x86_64-rhel-7.6/process/100%/debian-x86_64-2019-05-14.cgz/lkp-csl-2ap1/page_fault3/will-it-scale commit: c11fb13a11 ("Merge branch 'for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/hid/hid") 815744d751 ("mm: memcontrol: don't batch updates of local VM stats and events") c11fb13a117e5a67 815744d75152078cde5391fc1e3 ---------------- --------------------------- fail:runs %reproduction fail:runs | | | 16:4 183% 23:4 perf-profile.calltrace.cycles-pp.sync_regs.error_entry.testcase 18:4 204% 26:4 perf-profile.calltrace.cycles-pp.error_entry.testcase 0:4 3% 0:4 perf-profile.children.cycles-pp.error_exit 19:4 216% 28:4 perf-profile.children.cycles-pp.error_entry 0:4 2% 0:4 perf-profile.self.cycles-pp.error_exit 2:4 24% 3:4 perf-profile.self.cycles-pp.error_entry %stddev %change %stddev \ | \ 470022 +43.3% 673338 will-it-scale.per_process_ops 90244291 +43.3% 1.293e+08 will-it-scale.workload 33.13 -2.1% 32.44 boot-time.dhcp 13.47 ± 6% -1.0 12.47 mpstat.cpu.all.usr% 0.14 ± 10% +76.8% 0.25 ± 7% turbostat.CPU%c1 609.72 +9.7% 668.75 turbostat.PkgWatt 22201 ± 2% -11.1% 19733 ± 3% numa-meminfo.node2.Inactive 22201 ± 2% -12.0% 19545 ± 4% numa-meminfo.node2.Inactive(anon) 6060 ± 4% +15.5% 7000 ± 3% numa-meminfo.node2.KernelStack 30791 ± 32% -47.1% 16275 ± 22% numa-meminfo.node3.Inactive 30675 ± 33% -47.5% 16090 ± 22% numa-meminfo.node3.Inactive(anon) 15906 -1.4% 15683 proc-vmstat.nr_page_table_pages 60975063 +38.1% 84216668 proc-vmstat.numa_hit 60881605 +38.2% 84123351 proc-vmstat.numa_local 61181699 +38.1% 84469061 proc-vmstat.pgalloc_normal 2.713e+10 +43.3% 3.887e+10 proc-vmstat.pgfault 58818843 ± 5% +38.1% 81212599 ± 4% proc-vmstat.pgfree 14513924 ± 2% +41.8% 20585689 ± 2% numa-numastat.node0.local_node 14537287 ± 2% +41.7% 20601299 ± 2% numa-numastat.node0.numa_hit 15413984 ± 2% +37.3% 21168399 numa-numastat.node1.local_node 15437340 ± 2% +37.3% 21191634 numa-numastat.node1.numa_hit 15397026 +38.9% 21394060 numa-numastat.node2.local_node 15428108 +38.8% 21417417 numa-numastat.node2.numa_hit 15632711 +35.1% 21119563 numa-numastat.node3.local_node 15648357 +35.2% 21150655 numa-numastat.node3.numa_hit 444.19 ± 12% +41.5% 628.34 ± 3% sched_debug.cfs_rq:/.exec_clock.stddev 172846 ± 7% +19.1% 205841 sched_debug.cfs_rq:/.min_vruntime.stddev 0.05 ± 8% -22.1% 0.04 ± 5% sched_debug.cfs_rq:/.nr_running.stddev 171875 ± 7% +19.2% 204919 sched_debug.cfs_rq:/.spread0.stddev 280585 ± 89% -93.9% 17176 ± 64% sched_debug.cpu.avg_idle.min 24193 ± 31% +61.1% 38975 ± 24% sched_debug.cpu.nr_switches.max 1136 ± 13% +34.4% 1527 ± 16% sched_debug.cpu.ttwu_count.stddev 1018 ± 15% +44.2% 1467 ± 18% sched_debug.cpu.ttwu_local.stddev 8487551 ± 2% +35.9% 11538420 ± 2% numa-vmstat.node0.numa_hit 8464705 ± 2% +36.1% 11523132 ± 2% numa-vmstat.node0.numa_local 8798431 +32.5% 11661199 numa-vmstat.node1.numa_hit 8689509 ± 2% +32.9% 11552294 numa-vmstat.node1.numa_local 5568 ± 2% -12.9% 4847 ± 5% numa-vmstat.node2.nr_inactive_anon 6057 ± 4% +15.6% 7001 ± 3% numa-vmstat.node2.nr_kernel_stack 5571 ± 2% -12.9% 4851 ± 5% numa-vmstat.node2.nr_zone_inactive_anon 8648618 +35.6% 11724115 numa-vmstat.node2.numa_hit 8532135 +36.1% 11615170 numa-vmstat.node2.numa_local 7672 ± 33% -48.1% 3980 ± 17% numa-vmstat.node3.nr_inactive_anon 7673 ± 33% -48.1% 3983 ± 17% numa-vmstat.node3.nr_zone_inactive_anon 8891497 +30.8% 11626904 numa-vmstat.node3.numa_hit 8789979 +30.9% 11510256 numa-vmstat.node3.numa_local 130.25 ±165% -98.7% 1.75 ± 74% interrupts.CPU109.RES:Rescheduling_interrupts 131.00 ±137% -97.7% 3.00 ± 97% interrupts.CPU118.RES:Rescheduling_interrupts 598.75 ±121% -93.5% 38.75 ± 62% interrupts.CPU13.RES:Rescheduling_interrupts 10.25 ±156% +5961.0% 621.25 ±167% interrupts.CPU145.RES:Rescheduling_interrupts 688.50 ±129% -95.4% 31.50 ±107% interrupts.CPU16.RES:Rescheduling_interrupts 1.25 ± 34% +10960.0% 138.25 ±113% interrupts.CPU161.RES:Rescheduling_interrupts 779.50 ±149% -94.9% 39.75 ± 92% interrupts.CPU17.RES:Rescheduling_interrupts 104.25 ± 69% -93.8% 6.50 ± 35% interrupts.CPU177.RES:Rescheduling_interrupts 100.25 ± 90% -89.3% 10.75 ±118% interrupts.CPU182.RES:Rescheduling_interrupts 494.25 ± 60% -79.0% 103.75 ± 16% interrupts.CPU2.RES:Rescheduling_interrupts 4480 ± 16% -40.5% 2665 ± 55% interrupts.CPU24.CAL:Function_call_interrupts 19.50 ±152% +928.2% 200.50 ± 91% interrupts.CPU29.RES:Rescheduling_interrupts 5259 ± 34% +59.7% 8397 interrupts.CPU39.NMI:Non-maskable_interrupts 5259 ± 34% +59.7% 8397 interrupts.CPU39.PMI:Performance_monitoring_interrupts 5258 ± 34% +59.6% 8390 interrupts.CPU42.NMI:Non-maskable_interrupts 5258 ± 34% +59.6% 8390 interrupts.CPU42.PMI:Performance_monitoring_interrupts 5253 ± 34% +59.8% 8393 interrupts.CPU43.NMI:Non-maskable_interrupts 5253 ± 34% +59.8% 8393 interrupts.CPU43.PMI:Performance_monitoring_interrupts 5248 ± 34% +59.9% 8394 interrupts.CPU44.NMI:Non-maskable_interrupts 5248 ± 34% +59.9% 8394 interrupts.CPU44.PMI:Performance_monitoring_interrupts 5261 ± 34% +60.0% 8419 interrupts.CPU57.NMI:Non-maskable_interrupts 5261 ± 34% +60.0% 8419 interrupts.CPU57.PMI:Performance_monitoring_interrupts 7874 +20.1% 9459 ± 11% interrupts.CPU95.RES:Rescheduling_interrupts 217.00 ± 49% -80.2% 43.00 ± 93% interrupts.CPU96.RES:Rescheduling_interrupts 53003 ± 5% -13.3% 45949 ± 6% interrupts.RES:Rescheduling_interrupts 2.68 -31.4% 1.84 perf-stat.i.MPKI 4.694e+10 +43.1% 6.715e+10 perf-stat.i.branch-instructions 1.238e+08 +43.4% 1.775e+08 ± 2% perf-stat.i.branch-misses 59.57 +9.1 68.63 perf-stat.i.cache-miss-rate% 3.661e+08 +14.0% 4.173e+08 perf-stat.i.cache-misses 2.49 -31.0% 1.72 perf-stat.i.cpi 1557 -12.7% 1360 perf-stat.i.cycles-between-cache-misses 0.02 ±109% -0.0 0.00 ± 13% perf-stat.i.dTLB-load-miss-rate% 6.494e+10 +44.9% 9.411e+10 perf-stat.i.dTLB-loads 1.59e+09 +43.4% 2.28e+09 perf-stat.i.dTLB-store-misses 3.43e+10 +44.8% 4.965e+10 perf-stat.i.dTLB-stores 2.29e+11 +44.2% 3.301e+11 perf-stat.i.instructions 0.40 +44.8% 0.58 perf-stat.i.ipc 90220636 +43.3% 1.293e+08 perf-stat.i.minor-faults 6.99 ± 2% -6.0 0.96 ± 17% perf-stat.i.node-load-miss-rate% 2563994 ± 2% -67.3% 839546 ± 12% perf-stat.i.node-load-misses 34196685 ± 2% +154.9% 87152794 ± 5% perf-stat.i.node-loads 14.00 -7.5 6.48 perf-stat.i.node-store-miss-rate% 14863715 ± 2% -38.3% 9165413 ± 2% perf-stat.i.node-store-misses 91337940 +44.8% 1.323e+08 perf-stat.i.node-stores 90221535 +43.3% 1.293e+08 perf-stat.i.page-faults 2.68 -31.4% 1.84 perf-stat.overall.MPKI 59.57 +9.1 68.63 perf-stat.overall.cache-miss-rate% 2.49 -31.0% 1.72 perf-stat.overall.cpi 1557 -12.7% 1359 perf-stat.overall.cycles-between-cache-misses 0.02 ±109% -0.0 0.00 ± 13% perf-stat.overall.dTLB-load-miss-rate% 0.40 +44.8% 0.58 perf-stat.overall.ipc 6.98 ± 2% -6.0 0.96 ± 17% perf-stat.overall.node-load-miss-rate% 14.00 -7.5 6.48 perf-stat.overall.node-store-miss-rate% 4.677e+10 +43.1% 6.692e+10 perf-stat.ps.branch-instructions 1.234e+08 +43.4% 1.769e+08 ± 2% perf-stat.ps.branch-misses 3.647e+08 +14.0% 4.158e+08 perf-stat.ps.cache-misses 6.471e+10 +45.0% 9.379e+10 perf-stat.ps.dTLB-loads 1.584e+09 +43.5% 2.273e+09 perf-stat.ps.dTLB-store-misses 3.417e+10 +44.8% 4.949e+10 perf-stat.ps.dTLB-stores 2.281e+11 +44.2% 3.29e+11 perf-stat.ps.instructions 89897978 +43.3% 1.288e+08 perf-stat.ps.minor-faults 2554791 ± 2% -67.2% 836695 ± 12% perf-stat.ps.node-load-misses 34073965 ± 2% +154.9% 86855995 ± 5% perf-stat.ps.node-loads 14810461 ± 2% -38.3% 9134179 ± 2% perf-stat.ps.node-store-misses 91010555 +44.9% 1.318e+08 perf-stat.ps.node-stores 89898192 +43.3% 1.288e+08 perf-stat.ps.page-faults 6.812e+13 +44.2% 9.822e+13 perf-stat.total.instructions 11934 ± 6% +35.0% 16111 ± 12% softirqs.CPU120.RCU 11745 ± 4% +14.5% 13450 ± 5% softirqs.CPU122.RCU 11990 ± 7% +11.6% 13378 ± 4% softirqs.CPU124.RCU 11979 ± 5% +12.4% 13466 ± 4% softirqs.CPU126.RCU 11997 ± 6% +11.4% 13370 ± 5% softirqs.CPU127.RCU 12165 ± 3% +12.4% 13677 ± 4% softirqs.CPU128.RCU 12213 ± 4% +10.0% 13431 ± 4% softirqs.CPU129.RCU 11827 ± 4% +16.9% 13831 ± 5% softirqs.CPU130.RCU 11469 ± 9% +19.7% 13725 ± 4% softirqs.CPU131.RCU 11869 ± 5% +15.5% 13711 ± 3% softirqs.CPU132.RCU 11751 ± 4% +15.7% 13596 ± 4% softirqs.CPU134.RCU 11675 ± 5% +16.6% 13615 ± 6% softirqs.CPU135.RCU 11900 ± 5% +15.0% 13687 ± 3% softirqs.CPU136.RCU 11959 ± 5% +14.0% 13636 ± 4% softirqs.CPU137.RCU 11940 ± 5% +13.7% 13576 ± 3% softirqs.CPU138.RCU 11905 ± 6% +15.9% 13804 ± 5% softirqs.CPU139.RCU 12342 ± 5% +11.4% 13750 ± 6% softirqs.CPU140.RCU 11828 ± 4% +13.3% 13401 ± 3% softirqs.CPU141.RCU 11823 ± 4% +14.5% 13536 ± 3% softirqs.CPU142.RCU 11658 ± 7% +15.6% 13472 ± 3% softirqs.CPU143.RCU 133947 ± 19% -23.1% 102992 ± 3% softirqs.CPU143.TIMER 12294 ± 3% +6.5% 13090 ± 3% softirqs.CPU145.RCU 12118 ± 3% +7.3% 12999 softirqs.CPU146.RCU 12079 ± 3% +9.6% 13240 ± 2% softirqs.CPU149.RCU 11937 ± 3% +11.0% 13256 ± 2% softirqs.CPU155.RCU 12003 ± 3% +11.2% 13348 ± 4% softirqs.CPU156.RCU 11979 ± 6% +9.1% 13075 ± 4% softirqs.CPU158.RCU 11992 ± 3% +9.6% 13148 ± 4% softirqs.CPU159.RCU 12283 ± 5% +14.0% 13997 ± 9% softirqs.CPU167.RCU 11803 +12.4% 13267 ± 3% softirqs.CPU180.RCU 12018 ± 5% +6.8% 12838 ± 4% softirqs.CPU187.RCU 12493 ± 5% +13.6% 14192 ± 4% softirqs.CPU27.RCU 12587 ± 6% +13.8% 14328 ± 6% softirqs.CPU30.RCU 12864 ± 3% +9.6% 14103 ± 4% softirqs.CPU33.RCU 12555 ± 4% +12.9% 14181 ± 6% softirqs.CPU34.RCU 12422 ± 4% +17.1% 14545 ± 4% softirqs.CPU35.RCU 12235 +17.1% 14328 ± 2% softirqs.CPU36.RCU 12710 ± 5% +10.1% 13989 ± 3% softirqs.CPU37.RCU 12441 ± 4% +14.6% 14262 ± 3% softirqs.CPU38.RCU 12457 ± 4% +12.4% 14000 ± 3% softirqs.CPU39.RCU 12503 ± 5% +13.5% 14188 ± 2% softirqs.CPU40.RCU 12430 ± 4% +15.9% 14408 ± 3% softirqs.CPU41.RCU 12494 ± 5% +14.5% 14310 ± 3% softirqs.CPU42.RCU 12776 ± 4% +13.9% 14547 ± 5% softirqs.CPU43.RCU 12466 ± 4% +12.6% 14040 ± 2% softirqs.CPU45.RCU 12361 ± 5% +15.8% 14313 ± 3% softirqs.CPU46.RCU 11235 ± 8% +26.8% 14244 ± 2% softirqs.CPU47.RCU 118604 ± 8% -12.5% 103803 ± 2% softirqs.CPU47.TIMER 12832 ± 4% +8.0% 13857 ± 5% softirqs.CPU62.RCU 12303 ± 4% +7.4% 13208 ± 7% softirqs.CPU73.RCU 12603 ± 5% +19.3% 15040 ± 9% softirqs.CPU8.RCU 10873 ± 18% +24.4% 13522 ± 3% softirqs.CPU81.RCU 12259 +9.4% 13412 ± 3% softirqs.CPU83.RCU 12381 ± 2% +9.2% 13517 ± 4% softirqs.CPU87.RCU 12363 ± 2% +8.7% 13440 ± 3% softirqs.CPU88.RCU 12498 ± 2% +9.3% 13656 ± 3% softirqs.CPU89.RCU 12202 ± 2% +9.5% 13359 ± 5% softirqs.CPU92.RCU 12291 ± 3% +10.9% 13629 ± 3% softirqs.CPU94.RCU 15.61 -9.2 6.40 perf-profile.calltrace.cycles-pp.alloc_set_pte.finish_fault.__handle_mm_fault.handle_mm_fault.__do_page_fault 15.89 -9.1 6.81 perf-profile.calltrace.cycles-pp.finish_fault.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault 40.86 -8.9 31.99 perf-profile.calltrace.cycles-pp.handle_mm_fault.__do_page_fault.do_page_fault.page_fault.testcase 12.32 -8.8 3.57 ± 3% perf-profile.calltrace.cycles-pp.page_add_file_rmap.alloc_set_pte.finish_fault.__handle_mm_fault.handle_mm_fault 47.45 -8.1 39.30 perf-profile.calltrace.cycles-pp.__do_page_fault.do_page_fault.page_fault.testcase 49.07 -7.6 41.47 perf-profile.calltrace.cycles-pp.do_page_fault.page_fault.testcase 78.70 -7.5 71.19 perf-profile.calltrace.cycles-pp.testcase 9.92 -6.2 3.72 ± 16% perf-profile.calltrace.cycles-pp.page_remove_rmap.unmap_page_range.unmap_vmas.unmap_region.__do_munmap 6.82 ± 5% -5.5 1.31 ± 8% perf-profile.calltrace.cycles-pp.__count_memcg_events.handle_mm_fault.__do_page_fault.do_page_fault.page_fault 6.45 ± 3% -5.2 1.23 ± 25% perf-profile.calltrace.cycles-pp.__mod_memcg_state.__mod_lruvec_state.page_remove_rmap.unmap_page_range.unmap_vmas 6.23 ± 4% -5.2 1.06 ± 3% perf-profile.calltrace.cycles-pp.__mod_memcg_state.__mod_lruvec_state.page_add_file_rmap.alloc_set_pte.finish_fault 7.19 ± 2% -5.0 2.14 ± 18% perf-profile.calltrace.cycles-pp.__mod_lruvec_state.page_remove_rmap.unmap_page_range.unmap_vmas.unmap_region 7.00 ± 3% -5.0 2.04 ± 2% perf-profile.calltrace.cycles-pp.__mod_lruvec_state.page_add_file_rmap.alloc_set_pte.finish_fault.__handle_mm_fault 32.31 -4.0 28.28 perf-profile.calltrace.cycles-pp.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault.page_fault 14.76 -3.8 10.98 ± 9% perf-profile.calltrace.cycles-pp.unmap_page_range.unmap_vmas.unmap_region.__do_munmap.__vm_munmap 14.80 -3.8 11.04 ± 9% perf-profile.calltrace.cycles-pp.unmap_vmas.unmap_region.__do_munmap.__vm_munmap.__x64_sys_munmap 14.83 -3.8 11.07 ± 9% perf-profile.calltrace.cycles-pp.__x64_sys_munmap.do_syscall_64.entry_SYSCALL_64_after_hwframe.munmap 14.83 -3.8 11.07 ± 9% perf-profile.calltrace.cycles-pp.__do_munmap.__vm_munmap.__x64_sys_munmap.do_syscall_64.entry_SYSCALL_64_after_hwframe 14.83 -3.8 11.07 ± 9% perf-profile.calltrace.cycles-pp.__vm_munmap.__x64_sys_munmap.do_syscall_64.entry_SYSCALL_64_after_hwframe.munmap 14.83 -3.8 11.08 ± 9% perf-profile.calltrace.cycles-pp.munmap 14.82 -3.8 11.07 ± 9% perf-profile.calltrace.cycles-pp.unmap_region.__do_munmap.__vm_munmap.__x64_sys_munmap.do_syscall_64 14.83 -3.8 11.08 ± 9% perf-profile.calltrace.cycles-pp.entry_SYSCALL_64_after_hwframe.munmap 14.83 -3.8 11.08 ± 9% perf-profile.calltrace.cycles-pp.do_syscall_64.entry_SYSCALL_64_after_hwframe.munmap 3.96 ± 6% -3.7 0.27 ±100% perf-profile.calltrace.cycles-pp.lock_page_memcg.page_add_file_rmap.alloc_set_pte.finish_fault.__handle_mm_fault 1.72 -0.9 0.81 perf-profile.calltrace.cycles-pp._raw_spin_lock.alloc_set_pte.finish_fault.__handle_mm_fault.handle_mm_fault 1.30 ± 3% -0.6 0.69 perf-profile.calltrace.cycles-pp.down_read_trylock.__do_page_fault.do_page_fault.page_fault.testcase 1.31 -0.6 0.71 perf-profile.calltrace.cycles-pp.up_read.__do_page_fault.do_page_fault.page_fault.testcase 0.84 -0.2 0.64 perf-profile.calltrace.cycles-pp.unlock_page.fault_dirty_shared_page.__handle_mm_fault.handle_mm_fault.__do_page_fault 0.55 +0.1 0.67 ± 4% perf-profile.calltrace.cycles-pp.release_pages.tlb_flush_mmu.unmap_page_range.unmap_vmas.unmap_region 0.53 ± 3% +0.2 0.72 ± 2% perf-profile.calltrace.cycles-pp.current_time.file_update_time.__handle_mm_fault.handle_mm_fault.__do_page_fault 0.70 +0.2 0.90 ± 3% perf-profile.calltrace.cycles-pp.tlb_flush_mmu.unmap_page_range.unmap_vmas.unmap_region.__do_munmap 1.91 +0.3 2.18 perf-profile.calltrace.cycles-pp.fault_dirty_shared_page.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault 0.53 ± 2% +0.3 0.82 ± 4% perf-profile.calltrace.cycles-pp.vmacache_find.find_vma.__do_page_fault.do_page_fault.page_fault 0.92 +0.3 1.24 ± 3% perf-profile.calltrace.cycles-pp.___perf_sw_event.__perf_sw_event.do_page_fault.page_fault.testcase 0.60 ± 2% +0.3 0.93 ± 4% perf-profile.calltrace.cycles-pp.find_vma.__do_page_fault.do_page_fault.page_fault.testcase 0.92 +0.3 1.25 perf-profile.calltrace.cycles-pp.file_update_time.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault 0.41 ± 57% +0.4 0.77 ± 3% perf-profile.calltrace.cycles-pp.set_page_dirty.fault_dirty_shared_page.__handle_mm_fault.handle_mm_fault.__do_page_fault 0.96 +0.4 1.34 perf-profile.calltrace.cycles-pp.swapgs_restore_regs_and_return_to_usermode.testcase 1.37 +0.4 1.80 perf-profile.calltrace.cycles-pp.__perf_sw_event.do_page_fault.page_fault.testcase 0.84 ± 3% +0.5 1.30 ± 2% perf-profile.calltrace.cycles-pp.___perf_sw_event.__perf_sw_event.__do_page_fault.do_page_fault.page_fault 0.00 +0.6 0.55 ± 2% perf-profile.calltrace.cycles-pp.set_page_dirty.unmap_page_range.unmap_vmas.unmap_region.__do_munmap 0.00 +0.6 0.56 ± 4% perf-profile.calltrace.cycles-pp.__mod_node_page_state.__mod_lruvec_state.page_add_file_rmap.alloc_set_pte.finish_fault 0.00 +0.6 0.56 ± 2% perf-profile.calltrace.cycles-pp.page_mapping.set_page_dirty.fault_dirty_shared_page.__handle_mm_fault.handle_mm_fault 1.42 ± 2% +0.7 2.12 perf-profile.calltrace.cycles-pp.__perf_sw_event.__do_page_fault.do_page_fault.page_fault.testcase 1.74 +0.8 2.55 ± 2% perf-profile.calltrace.cycles-pp.xas_load.find_get_entry.find_lock_entry.shmem_getpage_gfp.shmem_fault 8.78 +2.7 11.48 ± 3% perf-profile.calltrace.cycles-pp.find_lock_entry.shmem_getpage_gfp.shmem_fault.__do_fault.__handle_mm_fault 9.54 +2.9 12.42 ± 3% perf-profile.calltrace.cycles-pp.shmem_getpage_gfp.shmem_fault.__do_fault.__handle_mm_fault.handle_mm_fault 10.25 +3.1 13.40 ± 3% perf-profile.calltrace.cycles-pp.shmem_fault.__do_fault.__handle_mm_fault.handle_mm_fault.__do_page_fault 10.57 +3.2 13.80 ± 3% perf-profile.calltrace.cycles-pp.__do_fault.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault 4.94 ± 2% +4.4 9.29 ± 4% perf-profile.calltrace.cycles-pp.find_get_entry.find_lock_entry.shmem_getpage_gfp.shmem_fault.__do_fault 84.06 +12.0 96.02 perf-profile.calltrace.cycles-pp.page_fault.testcase 12.70 ± 3% -10.4 2.31 ± 13% perf-profile.children.cycles-pp.__mod_memcg_state 14.21 ± 2% -10.0 4.23 ± 10% perf-profile.children.cycles-pp.__mod_lruvec_state 15.70 -9.2 6.54 perf-profile.children.cycles-pp.alloc_set_pte 15.93 -9.1 6.86 perf-profile.children.cycles-pp.finish_fault 40.99 -8.8 32.19 perf-profile.children.cycles-pp.handle_mm_fault 12.36 -8.8 3.60 ± 3% perf-profile.children.cycles-pp.page_add_file_rmap 47.57 -8.1 39.47 perf-profile.children.cycles-pp.__do_page_fault 49.12 -7.6 41.53 perf-profile.children.cycles-pp.do_page_fault 9.96 -6.2 3.77 ± 16% perf-profile.children.cycles-pp.page_remove_rmap 6.82 ± 5% -5.5 1.32 ± 8% perf-profile.children.cycles-pp.__count_memcg_events 5.18 ± 6% -4.4 0.77 ± 14% perf-profile.children.cycles-pp.lock_page_memcg 32.44 -4.0 28.45 perf-profile.children.cycles-pp.__handle_mm_fault 14.80 -3.8 11.04 ± 9% perf-profile.children.cycles-pp.unmap_vmas 14.80 -3.8 11.04 ± 9% perf-profile.children.cycles-pp.unmap_page_range 14.88 -3.8 11.12 ± 9% perf-profile.children.cycles-pp.entry_SYSCALL_64_after_hwframe 14.88 -3.8 11.12 ± 9% perf-profile.children.cycles-pp.do_syscall_64 14.83 -3.8 11.07 ± 9% perf-profile.children.cycles-pp.__x64_sys_munmap 14.83 -3.8 11.07 ± 9% perf-profile.children.cycles-pp.__do_munmap 14.83 -3.8 11.07 ± 9% perf-profile.children.cycles-pp.__vm_munmap 14.82 -3.8 11.07 ± 9% perf-profile.children.cycles-pp.unmap_region 14.83 -3.8 11.08 ± 9% perf-profile.children.cycles-pp.munmap 10.73 ± 8% -1.6 9.09 perf-profile.children.cycles-pp.native_irq_return_iret 1.76 -0.9 0.86 perf-profile.children.cycles-pp._raw_spin_lock 1.31 -0.6 0.71 perf-profile.children.cycles-pp.up_read 1.31 ± 3% -0.6 0.72 perf-profile.children.cycles-pp.down_read_trylock 0.84 -0.2 0.64 perf-profile.children.cycles-pp.unlock_page 0.37 ± 5% -0.2 0.18 ± 4% perf-profile.children.cycles-pp.__unlock_page_memcg 0.05 ± 9% +0.0 0.07 perf-profile.children.cycles-pp.p4d_offset 0.06 ± 6% +0.0 0.09 ± 4% perf-profile.children.cycles-pp.get_page_from_freelist 0.07 +0.0 0.10 ± 4% perf-profile.children.cycles-pp.pte_alloc_one 0.07 ± 6% +0.0 0.10 ± 5% perf-profile.children.cycles-pp.__alloc_pages_nodemask 0.07 ± 5% +0.0 0.10 ± 4% perf-profile.children.cycles-pp.__x86_indirect_thunk_rax 0.04 ± 57% +0.0 0.07 ± 6% perf-profile.children.cycles-pp.prep_new_page 0.03 ±100% +0.0 0.07 ± 7% perf-profile.children.cycles-pp.clear_page_erms 0.10 ± 4% +0.0 0.14 ± 10% perf-profile.children.cycles-pp.task_tick_fair 0.01 ±173% +0.0 0.05 ± 9% perf-profile.children.cycles-pp.unlock_page_memcg 0.03 ±100% +0.0 0.07 ± 10% perf-profile.children.cycles-pp.native_set_pte_at 0.10 +0.0 0.15 ± 3% perf-profile.children.cycles-pp.PageHuge 0.12 ± 6% +0.0 0.17 ± 14% perf-profile.children.cycles-pp.scheduler_tick 0.15 ± 8% +0.0 0.20 ± 2% perf-profile.children.cycles-pp._vm_normal_page 0.13 ± 3% +0.1 0.18 perf-profile.children.cycles-pp.page_rmapping 0.14 ± 3% +0.1 0.19 ± 8% perf-profile.children.cycles-pp.ktime_get_update_offsets_now 0.16 ± 2% +0.1 0.21 ± 4% perf-profile.children.cycles-pp.fpregs_assert_state_consistent 0.12 ± 4% +0.1 0.18 ± 2% perf-profile.children.cycles-pp.rcu_all_qs 0.00 +0.1 0.06 ± 7% perf-profile.children.cycles-pp.hrtimer_active 0.17 ± 6% +0.1 0.22 ± 15% perf-profile.children.cycles-pp.tick_sched_handle 0.13 ± 3% +0.1 0.19 ± 2% perf-profile.children.cycles-pp.pmd_pfn 0.16 ± 6% +0.1 0.22 ± 14% perf-profile.children.cycles-pp.update_process_times 0.17 ± 2% +0.1 0.24 perf-profile.children.cycles-pp.pmd_page_vaddr 0.21 ± 8% +0.1 0.27 ± 2% perf-profile.children.cycles-pp.perf_exclude_event 0.11 ± 23% +0.1 0.17 ± 11% perf-profile.children.cycles-pp.timespec64_trunc 0.09 ± 4% +0.1 0.16 ± 32% perf-profile.children.cycles-pp.mem_cgroup_from_task 0.22 ± 7% +0.1 0.30 ± 15% perf-profile.children.cycles-pp.tick_sched_timer 0.15 ± 3% +0.1 0.24 perf-profile.children.cycles-pp.free_pages_and_swap_cache 0.20 ± 2% +0.1 0.29 ± 2% perf-profile.children.cycles-pp.mark_page_accessed 0.20 ± 14% +0.1 0.29 ± 15% perf-profile.children.cycles-pp.ktime_get_coarse_real_ts64 0.25 ± 4% +0.1 0.34 perf-profile.children.cycles-pp.__might_sleep 0.12 ± 4% +0.1 0.23 ± 2% perf-profile.children.cycles-pp.__tlb_remove_page_size 0.27 ± 4% +0.1 0.37 ± 12% perf-profile.children.cycles-pp.__hrtimer_run_queues 0.23 ± 2% +0.1 0.34 ± 2% perf-profile.children.cycles-pp._cond_resched 0.36 ± 2% +0.1 0.48 ± 2% perf-profile.children.cycles-pp.prepare_exit_to_usermode 0.31 ± 4% +0.1 0.43 ± 4% perf-profile.children.cycles-pp.xas_start 0.56 +0.1 0.69 ± 3% perf-profile.children.cycles-pp.release_pages 0.66 +0.1 0.79 perf-profile.children.cycles-pp.___might_sleep 0.38 +0.1 0.51 perf-profile.children.cycles-pp.__set_page_dirty_no_writeback 0.69 ± 4% +0.1 0.83 ± 11% perf-profile.children.cycles-pp.apic_timer_interrupt 0.58 ± 4% +0.2 0.75 ± 12% perf-profile.children.cycles-pp.hrtimer_interrupt 0.61 ± 4% +0.2 0.79 ± 11% perf-profile.children.cycles-pp.smp_apic_timer_interrupt 0.56 ± 2% +0.2 0.75 ± 2% perf-profile.children.cycles-pp.current_time 0.59 ± 4% +0.2 0.78 ± 5% perf-profile.children.cycles-pp.pmd_devmap_trans_unstable 0.71 +0.2 0.92 ± 3% perf-profile.children.cycles-pp.tlb_flush_mmu 1.97 +0.3 2.27 perf-profile.children.cycles-pp.fault_dirty_shared_page 0.55 ± 2% +0.3 0.85 ± 4% perf-profile.children.cycles-pp.vmacache_find 1.17 ± 2% +0.3 1.48 perf-profile.children.cycles-pp.page_mapping 0.94 +0.3 1.28 perf-profile.children.cycles-pp.file_update_time 0.72 ± 2% +0.3 1.06 ± 9% perf-profile.children.cycles-pp.__mod_node_page_state 0.63 ± 2% +0.3 0.97 ± 3% perf-profile.children.cycles-pp.find_vma 0.96 +0.4 1.34 perf-profile.children.cycles-pp.swapgs_restore_regs_and_return_to_usermode 0.93 ± 2% +0.4 1.38 ± 2% perf-profile.children.cycles-pp.set_page_dirty 1.89 ± 2% +0.8 2.68 perf-profile.children.cycles-pp.___perf_sw_event 1.79 +0.8 2.61 perf-profile.children.cycles-pp.xas_load 2.81 +1.1 3.94 perf-profile.children.cycles-pp.__perf_sw_event 4.28 +1.9 6.15 perf-profile.children.cycles-pp.sync_regs 67.10 +2.4 69.48 perf-profile.children.cycles-pp.page_fault 8.85 +2.7 11.58 ± 3% perf-profile.children.cycles-pp.find_lock_entry 9.56 +2.9 12.45 ± 3% perf-profile.children.cycles-pp.shmem_getpage_gfp 10.27 +3.1 13.42 ± 3% perf-profile.children.cycles-pp.shmem_fault 10.58 +3.2 13.81 ± 3% perf-profile.children.cycles-pp.__do_fault 85.11 +3.8 88.87 perf-profile.children.cycles-pp.testcase 4.97 ± 2% +4.4 9.34 ± 4% perf-profile.children.cycles-pp.find_get_entry 12.60 ± 3% -10.3 2.25 ± 14% perf-profile.self.cycles-pp.__mod_memcg_state 6.80 ± 5% -5.5 1.29 ± 8% perf-profile.self.cycles-pp.__count_memcg_events 5.11 ± 6% -4.4 0.71 ± 14% perf-profile.self.cycles-pp.lock_page_memcg 2.84 ± 2% -1.7 1.09 perf-profile.self.cycles-pp.find_lock_entry 10.73 ± 8% -1.6 9.08 perf-profile.self.cycles-pp.native_irq_return_iret 1.73 -0.9 0.83 perf-profile.self.cycles-pp._raw_spin_lock 1.29 ± 4% -0.6 0.68 perf-profile.self.cycles-pp.down_read_trylock 1.30 -0.6 0.70 perf-profile.self.cycles-pp.up_read 1.37 -0.3 1.05 ± 3% perf-profile.self.cycles-pp.page_add_file_rmap 0.82 -0.2 0.61 perf-profile.self.cycles-pp.unlock_page 0.35 ± 4% -0.2 0.17 ± 3% perf-profile.self.cycles-pp.__unlock_page_memcg 0.05 +0.0 0.07 ± 5% perf-profile.self.cycles-pp.__x86_indirect_thunk_rax 0.14 ± 6% +0.0 0.16 ± 10% perf-profile.self.cycles-pp.perf_swevent_event 0.08 +0.0 0.11 ± 3% perf-profile.self.cycles-pp.PageHuge 0.11 ± 4% +0.0 0.14 perf-profile.self.cycles-pp.page_rmapping 0.09 ± 4% +0.0 0.13 perf-profile.self.cycles-pp.find_vma 0.03 ±100% +0.0 0.07 ± 7% perf-profile.self.cycles-pp.clear_page_erms 0.22 ± 6% +0.0 0.26 perf-profile.self.cycles-pp.__do_fault 0.09 +0.0 0.13 ± 3% perf-profile.self.cycles-pp.rcu_all_qs 0.16 ± 2% +0.0 0.21 ± 3% perf-profile.self.cycles-pp.prepare_exit_to_usermode 0.01 ±173% +0.0 0.06 ± 14% perf-profile.self.cycles-pp.native_set_pte_at 0.13 ± 8% +0.0 0.18 ± 3% perf-profile.self.cycles-pp._vm_normal_page 0.13 ± 5% +0.0 0.18 ± 9% perf-profile.self.cycles-pp.ktime_get_update_offsets_now 0.17 ± 9% +0.0 0.22 perf-profile.self.cycles-pp.perf_exclude_event 0.11 +0.1 0.16 ± 4% perf-profile.self.cycles-pp._cond_resched 0.00 +0.1 0.05 perf-profile.self.cycles-pp.unlock_page_memcg 0.12 ± 3% +0.1 0.17 ± 2% perf-profile.self.cycles-pp.pmd_pfn 0.00 +0.1 0.05 ± 8% perf-profile.self.cycles-pp.pmd_devmap 0.15 ± 5% +0.1 0.21 ± 4% perf-profile.self.cycles-pp.fpregs_assert_state_consistent 0.16 ± 2% +0.1 0.22 perf-profile.self.cycles-pp.pmd_page_vaddr 0.00 +0.1 0.06 ± 7% perf-profile.self.cycles-pp.hrtimer_active 0.10 ± 19% +0.1 0.16 ± 9% perf-profile.self.cycles-pp.timespec64_trunc 0.08 ± 10% +0.1 0.15 ± 33% perf-profile.self.cycles-pp.mem_cgroup_from_task 0.80 +0.1 0.87 perf-profile.self.cycles-pp.__mod_lruvec_state 0.15 ± 2% +0.1 0.23 ± 3% perf-profile.self.cycles-pp.free_pages_and_swap_cache 0.19 ± 2% +0.1 0.27 ± 3% perf-profile.self.cycles-pp.mark_page_accessed 0.23 ± 5% +0.1 0.32 perf-profile.self.cycles-pp.__might_sleep 0.18 ± 15% +0.1 0.28 ± 16% perf-profile.self.cycles-pp.ktime_get_coarse_real_ts64 0.20 ± 3% +0.1 0.29 perf-profile.self.cycles-pp.do_page_fault 0.23 +0.1 0.33 perf-profile.self.cycles-pp.finish_fault 0.11 ± 4% +0.1 0.21 ± 4% perf-profile.self.cycles-pp.__tlb_remove_page_size 0.28 ± 4% +0.1 0.39 ± 3% perf-profile.self.cycles-pp.xas_start 0.21 ± 17% +0.1 0.32 ± 6% perf-profile.self.cycles-pp.fault_dirty_shared_page 0.54 +0.1 0.66 ± 4% perf-profile.self.cycles-pp.release_pages 0.34 +0.1 0.46 perf-profile.self.cycles-pp.__set_page_dirty_no_writeback 0.65 +0.1 0.78 perf-profile.self.cycles-pp.___might_sleep 0.39 +0.1 0.53 ± 2% perf-profile.self.cycles-pp.file_update_time 0.29 +0.2 0.44 perf-profile.self.cycles-pp.set_page_dirty 0.70 ± 6% +0.2 0.86 ± 3% perf-profile.self.cycles-pp.shmem_getpage_gfp 0.56 ± 4% +0.2 0.74 ± 5% perf-profile.self.cycles-pp.pmd_devmap_trans_unstable 0.71 ± 2% +0.3 0.97 perf-profile.self.cycles-pp.shmem_fault 0.59 +0.3 0.85 perf-profile.self.cycles-pp.swapgs_restore_regs_and_return_to_usermode 0.73 +0.3 1.00 perf-profile.self.cycles-pp.page_fault 1.12 ± 2% +0.3 1.41 perf-profile.self.cycles-pp.page_mapping 0.52 ± 2% +0.3 0.82 ± 4% perf-profile.self.cycles-pp.vmacache_find 0.90 +0.3 1.22 ± 2% perf-profile.self.cycles-pp.__perf_sw_event 0.71 ± 2% +0.3 1.05 ± 9% perf-profile.self.cycles-pp.__mod_node_page_state 0.91 +0.4 1.29 perf-profile.self.cycles-pp.alloc_set_pte 1.29 +0.6 1.88 perf-profile.self.cycles-pp.__do_page_fault 1.57 +0.7 2.23 ± 2% perf-profile.self.cycles-pp.handle_mm_fault 1.48 +0.7 2.17 ± 2% perf-profile.self.cycles-pp.xas_load 1.56 ± 3% +0.7 2.26 ± 2% perf-profile.self.cycles-pp.___perf_sw_event 2.55 ± 4% +1.1 3.64 perf-profile.self.cycles-pp.__handle_mm_fault 2.99 +1.8 4.83 ± 8% perf-profile.self.cycles-pp.unmap_page_range 4.27 +1.9 6.13 perf-profile.self.cycles-pp.sync_regs 3.14 ± 3% +3.5 6.62 ± 6% perf-profile.self.cycles-pp.find_get_entry 18.54 +10.1 28.67 perf-profile.self.cycles-pp.testcase will-it-scale.per_process_ops 700000 +-+---------------------O------------------------------------------+ | O O O O O O O O O O O O O | O O O O O O O | 650000 +-+ | | | | | 600000 +-+ | | | 550000 +-+ | | | | | 500000 +-+ | | .+.. .+.. +.. ..+..+..+..+.. .+.. .+.. | |..+..+. +. +.. .. +..+. +..+. +..+. .| 450000 +-+----------------------------------------------------------------+ will-it-scale.workload 1.35e+08 +-+--------------------------------------------------------------+ 1.3e+08 +-+ O O O O O O O O O O O O O | O O O O O O O O | 1.25e+08 +-+ | 1.2e+08 +-+ | | | 1.15e+08 +-+ | 1.1e+08 +-+ | 1.05e+08 +-+ | | | 1e+08 +-+ | 9.5e+07 +-+ | |..+.. .+.. .+.. .+.. .+.+..+..+.. .+.. .+.. .| 9e+07 +-+ +. +. +..+. +..+. +..+. +..+. +. | 8.5e+07 +-+--------------------------------------------------------------+ [*] bisect-good sample [O] bisect-bad sample 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, Rong Chen

11 months, 2 weeks

1
0
0 / 0

[x86/hpet] 286b15db78: BUG:KASAN:wild-memory-access_in_t

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: 286b15db78dc9741a47d082016dfc5fbcc31bd46 ("x86/hpet: Use channel for legacy clockevent storage") https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git WIP.x86/hpet in testcase: locktorture with following parameters: runtime: 300s test: default test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors. test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +------------------------------------------+------------+------------+ | | dfd001e50c | 286b15db78 | +------------------------------------------+------------+------------+ | boot_successes | 14 | 0 | | boot_failures | 0 | 16 | | BUG:KASAN:wild-memory-access_in_t | 0 | 16 | | general_protection_fault:#[##] | 0 | 16 | | RIP:try_module_get | 0 | 16 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 16 | +------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> [ 2.801166] BUG: KASAN: wild-memory-access in try_module_get+0x78/0x1bf [ 2.803334] Read of size 4 at addr 6b6b6b6b6b6b6b6b by task swapper/0 [ 2.805479] [ 2.806045] CPU: 0 PID: 0 Comm: swapper Not tainted 5.2.0-rc5-00029-g286b15d #1 [ 2.808444] Call Trace: [ 2.809272] dump_stack+0x19/0x1b [ 2.810385] __kasan_report+0x151/0x167 [ 2.811724] ? try_module_get+0x78/0x1bf [ 2.813002] kasan_report+0x33/0x3f [ 2.814187] ? try_module_get+0x78/0x1bf [ 2.815457] __asan_load4+0x8a/0x8c [ 2.816740] try_module_get+0x78/0x1bf [ 2.817985] ? free_modinfo_version+0x3d/0x3d [ 2.819919] tick_check_new_device+0x47/0x98 [ 2.821370] clockevents_register_device+0xfe/0x1f5 [ 2.822983] clockevents_config_and_register+0x5f/0x67 [ 2.824682] hpet_enable+0x5bc/0x693 [ 2.825947] hpet_time_init+0x21/0x9c [ 2.827129] x86_late_time_init+0x1e/0x59 [ 2.828472] start_kernel+0x5f8/0x6ec [ 2.829661] ? thread_stack_cache_init+0x2e/0x2e [ 2.831245] ? x86_early_init_platform_quirks+0x9b/0x172 [ 2.832966] ? __asan_loadN+0x23/0x25 [ 2.834211] x86_64_start_reservations+0x4f/0x70 [ 2.835747] x86_64_start_kernel+0x7f/0xa2 [ 2.837061] secondary_startup_64+0xa4/0xb0 [ 2.838447] ================================================================== [ 2.840861] Disabling lock debugging due to kernel taint [ 2.842663] general protection fault: 0000 [#1] KASAN [ 2.844382] CPU: 0 PID: 0 Comm: swapper Tainted: G B 5.2.0-rc5-00029-g286b15d #1 [ 2.847271] RIP: 0010:try_module_get+0x78/0x1bf [ 2.848790] Code: 40 04 04 f2 f2 f2 c7 40 08 f3 f3 f3 f3 4d 85 ed b2 01 0f 84 2f 01 00 00 4c 8d 7d d8 ff 05 f8 04 9a 01 4c 89 ef e8 ef 7e 10 00 <41> 83 7d 00 02 75 07 31 d2 e9 09 01 00 00 49 8d 9d 78 03 00 00 be [ 2.854808] RSP: 0000:ffffffffafe07ce0 EFLAGS: 00010082 [ 2.856502] RAX: ffffffffafe96880 RBX: ffff888062890008 RCX: ffffffffae4ca1c8 [ 2.858889] RDX: fffffbfff5fc0f6c RSI: 0000000000000007 RDI: ffffffffb056fdf4 [ 2.861155] RBP: ffffffffafe07d68 R08: dffffc0000000000 R09: 0000000000000001 [ 2.863504] R10: fffffbfff60adfbe R11: 6775626564206b63 R12: 1ffffffff5fc0f9c [ 2.865873] R13: 6b6b6b6b6b6b6b6b R14: ffffffffb00e93e0 R15: ffffffffafe07d40 [ 2.868589] FS: 0000000000000000(0000) GS:ffffffffafeb7000(0000) knlGS:0000000000000000 [ 2.871247] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 2.873104] CR2: 00000000ffffffff CR3: 0000000070e8b000 CR4: 00000000000006b0 [ 2.875445] Call Trace: [ 2.876304] ? free_modinfo_version+0x3d/0x3d [ 2.877791] tick_check_new_device+0x47/0x98 [ 2.879164] clockevents_register_device+0xfe/0x1f5 [ 2.880818] clockevents_config_and_register+0x5f/0x67 [ 2.882473] hpet_enable+0x5bc/0x693 [ 2.883634] hpet_time_init+0x21/0x9c [ 2.885233] x86_late_time_init+0x1e/0x59 [ 2.886634] start_kernel+0x5f8/0x6ec [ 2.887795] ? thread_stack_cache_init+0x2e/0x2e [ 2.889262] ? x86_early_init_platform_quirks+0x9b/0x172 [ 2.890931] ? __asan_loadN+0x23/0x25 [ 2.892123] x86_64_start_reservations+0x4f/0x70 [ 2.893602] x86_64_start_kernel+0x7f/0xa2 [ 2.895030] secondary_startup_64+0xa4/0xb0 [ 2.896402] Modules linked in: [ 2.897510] ---[ end trace 9fb9fcfe81bfae10 ]--- To reproduce: # build kernel cd linux cp config-5.2.0-rc5-00029-g286b15d .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage git clone https://github.com/intel/lkp-tests.git cd lkp-tests find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email Thanks, Rong Chen

11 months, 2 weeks

1
0
0 / 0

a7aafd3922 [ 3.300499] WARNING: CPU: 0 PID: 0 at arch/x86/kernel/process_64.c:173 __rdgsbase_inactive

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/cpu commit a7aafd3922587ffc7590310d543ed6a6e8a1e355 Author: Andy Lutomirski <luto(a)kernel.org> AuthorDate: Tue Jun 18 16:48:21 2019 -0700 Commit: Andy Lutomirski <luto(a)kernel.org> CommitDate: Tue Jun 18 16:48:21 2019 -0700 assertions for "x86/fsgsbase/64: Enable FSGSBASE instructions in helper functions" e31ce026b4 Documentation/x86/64: Add documentation for GS/FS addressing mode a7aafd3922 assertions for "x86/fsgsbase/64: Enable FSGSBASE instructions in helper functions" d6d8c330a7 Tiny fixup for "x86/entry/64: Handle FSGSBASE enabled paranoid entry/exit" +--------------------------------------------------------------+------------+------------+------------+ | | e31ce026b4 | a7aafd3922 | d6d8c330a7 | +--------------------------------------------------------------+------------+------------+------------+ | boot_successes | 39 | 4 | 0 | | boot_failures | 14 | 18 | 16 | | BUG:kernel_hang_in_boot-around-mounting-root_stage | 13 | 4 | 1 | | Mem-Info | 1 | | | | BUG:kernel_reboot-without-warning_in_test_stage | 0 | 2 | | | BUG:soft_lockup-CPU##stuck_for#s | 0 | 1 | | | RIP:memset_orig | 0 | 1 | | | Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 1 | | | WARNING:at_arch/x86/kernel/process_64.c:#__rdgsbase_inactive | 0 | 13 | 16 | | RIP:__rdgsbase_inactive | 0 | 13 | 16 | +--------------------------------------------------------------+------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <lkp(a)intel.com> [ 3.266945] Spectre V2 : Spectre mitigation: kernel not compiled with retpoline; no mitigation available! [ 3.266957] Speculative Store Bypass: Vulnerable [ 3.280762] MDS: Vulnerable: Clear CPU buffers attempted, no microcode [ 3.289201] ------------[ cut here ]------------ [ 3.296939] IRQs not disabled as expected [ 3.300499] WARNING: CPU: 0 PID: 0 at arch/x86/kernel/process_64.c:173 __rdgsbase_inactive+0xc9/0x100 [ 3.306882] Modules linked in: [ 3.306882] CPU: 0 PID: 0 Comm: swapper Tainted: G T 5.2.0-rc4-00022-ga7aafd3 #2 [ 3.306882] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 3.306882] RIP: 0010:__rdgsbase_inactive+0xc9/0x100 [ 3.306882] Code: 00 00 31 d2 be 01 00 00 00 48 c7 c7 60 59 d4 82 c6 05 f8 9e d2 01 01 e8 e5 40 1f 00 48 c7 c7 18 e7 76 82 31 c0 e8 97 43 0d 00 <0f> 0b be 01 00 00 00 b9 01 00 00 00 31 d2 48 c7 c7 30 59 d4 82 e8 [ 3.306882] RSP: 0000:ffffffff82a07cb0 EFLAGS: 00010286 [ 3.306882] RAX: 000000000000001d RBX: 0000000000000001 RCX: ffffffff8114f96f [ 3.306882] RDX: 0000000000000007 RSI: dffffc0000000000 RDI: ffffffff82a3a8dc [ 3.306882] RBP: ffffffff82a3a8c0 R08: fffffbfff054b905 R09: 0000000000000000 [ 3.306882] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88801a30ff58 [ 3.306882] R13: ffff88801a308000 R14: ffffffff82a3ccb0 R15: ffffffff81ecd7e0 [ 3.306882] FS: 0000000000000000(0000) GS:ffffffff82a49000(0000) knlGS:0000000000000000 [ 3.306882] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 3.306882] CR2: 00000000ffffffff CR3: 0000000002a33000 CR4: 00000000001506b0 [ 3.306882] Call Trace: [ 3.306882] copy_thread_tls+0xf2/0x590 [ 3.306882] ? ftrace_likely_update+0x2c/0x60 [ 3.306882] ? copy_namespaces+0xdc/0x1b0 [ 3.306882] copy_process+0x21e0/0x3da0 [ 3.306882] ? rest_init+0x140/0x140 [ 3.306882] _do_fork+0xe2/0x650 [ 3.306882] ? acpi_hw_register_read+0xfe/0x109 [ 3.306882] ? acpi_read_bit_register+0x73/0x84 [ 3.306882] ? rest_init+0x140/0x140 [ 3.306882] kernel_thread+0x2f/0x40 [ 3.306882] rest_init+0x22/0x140 [ 3.306882] start_kernel+0x883/0x8b3 [ 3.306882] secondary_startup_64+0xb6/0xc0 [ 3.306882] irq event stamp: 4658 [ 3.306882] hardirqs last enabled at (4657): [<ffffffff8115d2b1>] console_unlock+0x421/0x660 [ 3.306882] hardirqs last disabled at (4658): [<ffffffff810020eb>] trace_hardirqs_off_thunk+0x1a/0x1c [ 3.306882] softirqs last enabled at (4646): [<ffffffff82200494>] __do_softirq+0x494/0x525 [ 3.306882] softirqs last disabled at (4639): [<ffffffff810ea519>] irq_exit+0xc9/0xe0 [ 3.306882] random: get_random_bytes called from init_oops_id+0x30/0x40 with crng_init=0 [ 3.306882] ---[ end trace 00f3be06b399857d ]--- [ 3.322263] Performance Events: no PMU driver, software events only. # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD git bisect start 5b08f90b003be883c2894a2922917f4cd271d294 9e0babf2c06c73cda2c0cd37a1653d823adb40ec -- git bisect bad 4a1a4f130bd632752c6413c5a6365dde58c0d0de # 15:39 B 1 4 1 1 Merge 'kristo/j721-next' into devel-catchup-201906190948 git bisect bad 71432521a98a0e236709cb5216d7579e75ec5c14 # 16:23 B 0 1 16 0 Merge 'linux-review/Hugues-Fruchet/media-st-mipid02-add-support-of-V4L2_CID_LINK_FREQ/20190619-040124' into devel-catchup-201906190948 git bisect bad 9beaf8bbf8cb1dec2e04a49220f2949c772d92d3 # 17:15 B 1 2 0 0 Merge 'linux-review/Andreas-Gruenbacher/fs-Move-mark_inode_dirty-out-of-__generic_write_end/20190619-061402' into devel-catchup-201906190948 git bisect good b76ae927febf4b66b66db71b26f39e62b6ceed79 # 18:13 G 13 0 2 2 Merge 'saeed/net-next' into devel-catchup-201906190948 git bisect bad b121ef1c28e1df18a5186f6b3610ba6b2894701f # 18:58 B 0 1 16 0 Merge 'linux-review/Dmitry-Osipenko/media-dt-bindings-tegra-vde-Document-new-optional-IOMMU-property/20190619-083111' into devel-catchup-201906190948 git bisect bad d4d5c6f4336c58a31794bf5c736e4601590e756a # 19:27 B 1 1 0 0 Merge 'luto/x86/cpu' into devel-catchup-201906190948 git bisect good c5621190083376929259b7e937b09c38ddb7a9a9 # 20:07 G 15 0 4 4 x86/process/64: Use FSGSBASE instructions on thread copy git bisect good ce21b2c3f5494fccea262ff3c34f8fca180b04c2 # 20:30 G 15 0 1 1 selftests/x86/fsgsbase: Test RD/WRGSBASE git bisect good 948cb19b6709fd68b17baf9fb88f3febe49cc079 # 20:54 G 16 0 2 2 x86/elf: Enumerate kernel FSGSBASE capability in AT_HWCAP2 git bisect bad a7aafd3922587ffc7590310d543ed6a6e8a1e355 # 21:24 B 0 1 16 0 assertions for "x86/fsgsbase/64: Enable FSGSBASE instructions in helper functions" git bisect good e31ce026b4b141e3245fde4b7698e310b20e056e # 22:19 G 15 0 4 4 Documentation/x86/64: Add documentation for GS/FS addressing mode # first bad commit: [a7aafd3922587ffc7590310d543ed6a6e8a1e355] assertions for "x86/fsgsbase/64: Enable FSGSBASE instructions in helper functions" git bisect good e31ce026b4b141e3245fde4b7698e310b20e056e # 22:28 G 48 0 9 13 Documentation/x86/64: Add documentation for GS/FS addressing mode # extra tests on HEAD of linux-devel/devel-catchup-201906190948 git bisect bad 5b08f90b003be883c2894a2922917f4cd271d294 # 22:29 B 12 289 0 79 0day head guard for 'devel-catchup-201906190948' # extra tests on tree/branch luto/x86/cpu git bisect bad d6d8c330a77ce5816d2683e3fc58ee8ae8bfa676 # 23:05 B 0 1 16 0 Tiny fixup for "x86/entry/64: Handle FSGSBASE enabled paranoid entry/exit" # extra tests with first bad commit reverted git bisect good a887489e9faf1d68ffe99c258474306069eb6585 # 23:48 G 15 0 2 2 Revert "assertions for "x86/fsgsbase/64: Enable FSGSBASE instructions in helper functions"" --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/pipermail/lkp Intel Corporation

11 months, 2 weeks

1
0
0 / 0

[IMA] 686027912d: BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: 686027912dd548026757aea664261010177f9625 ("IMA: support for per policy rule template formats") https://git.kernel.org/cgit/linux/kernel/git/zohar/linux-integrity.git next-queued-testing in testcase: pm-qa with following parameters: test: thermal on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +----------------------------------------------------------------+------------+------------+ | | 33fa2a9ce3 | 686027912d | +----------------------------------------------------------------+------------+------------+ | boot_successes | 18 | 0 | | boot_failures | 16 | 35 | | BUG:kernel_reboot-without-warning_in_test_stage | 16 | | | BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 35 | +----------------------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> [ 12.865737] BUG: sleeping function called from invalid context at mm/slab.h:418 [ 12.874069] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: swapper/0 [ 12.876961] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 5.2.0-rc1-00010-g6860279 #1 [ 12.880463] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 12.884300] Call Trace: [ 12.885730] dump_stack+0x5c/0x7b [ 12.897914] ___might_sleep+0xf1/0x110 [ 12.899805] __kmalloc+0x186/0x220 [ 12.901552] template_desc_init_fields+0x1a3/0x2a0 [ 12.904061] ? selinux_inode_getsecid+0x30/0x30 [ 12.906174] ? security_inode_alloc+0x4d/0x90 [ 12.908279] ? __d_instantiate+0x34/0xf0 [ 12.910235] ? d_instantiate+0x3d/0x60 [ 12.912168] ? set_debug_rodata+0x11/0x11 [ 12.914103] lookup_template_desc+0x7f/0xa0 [ 12.916137] ? hash_setup+0xa6/0xa6 [ 12.917938] ima_template_desc_current+0x2e/0x40 [ 12.920104] hash_setup+0xe/0xa6 [ 12.921819] init_ima+0x17/0x9e [ 12.923415] do_one_initcall+0x46/0x214 [ 12.925327] kernel_init_freeable+0x1c7/0x272 [ 12.927389] ? rest_init+0xd0/0xd0 [ 12.929111] kernel_init+0xa/0x110 [ 12.930879] ret_from_fork+0x35/0x40 [ 12.932775] ima: No TPM chip found, activating TPM-bypass! [ 12.935327] ima: Allocated hash algorithm: sha1 [ 12.937482] No architecture policies found [ 12.941471] evm: Initialising EVM extended attributes: [ 12.943889] evm: security.selinux [ 12.945555] evm: security.apparmor [ 12.947334] evm: security.ima [ 12.948909] evm: security.capability [ 12.950747] evm: HMAC attrs: 0x1 [ 12.957065] PM: Magic number: 7:871:763 [ 12.959181] psmouse serio1: hash matches [ 12.961495] rtc_cmos 00:00: setting system clock to 2019-06-19T02:48:06 UTC (1560912486) [ 12.973895] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX [ 12.979938] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 12.991948] Sending DHCP requests ., OK [ 12.994762] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15 [ 12.997916] IP-Config: Complete: [ 12.999701] device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2 [ 13.004101] host=vm-snb-2G-442, domain=, nis-domain=(none) [ 13.006825] bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath= [ 13.006827] nameserver0=10.0.2.3 [ 13.016218] Freeing unused decrypted memory: 2040K [ 13.020818] Freeing unused kernel image memory: 2504K [ 13.028936] Write protecting the kernel read-only data: 22528k [ 13.033912] Freeing unused kernel image memory: 2012K [ 13.038439] Freeing unused kernel image memory: 1920K [ 13.041055] rodata_test: all tests were successful [ 13.043511] Run /init as init process SELinux: Could not open policy file <= /etc/selinux/targeted/policy/policy.31: No such file or directory [ 13.060345] systemd[1]: RTC configured in localtime, applying delta of 480 minutes to system time. [ 13.074703] random: systemd: uninitialized urandom read (16 bytes read) [ 13.079213] random: systemd: uninitialized urandom read (16 bytes read) [ 13.082294] random: systemd: uninitialized urandom read (16 bytes read) Mounting POSIX Message Queue File System... Mounting Huge Pages File System... Mounting Debug File System... Mounting RPC Pipe File System... Starting Remount Root and Kernel File Systems... Starting Load Kernel Modules... Starting Journal Service... Mounting Configuration File System... Starting Apply Kernel Variables... Starting udev Coldplug all Devices... Starting Load/Save Random Seed... Starting Create Static Device Nodes in /dev... Starting Preprocess NFS configuration... Starting udev Kernel Device Manager... Starting Raise network interfaces... Starting Flush Journal to Persistent Storage... Starting Create Volatile Files and Directories... Starting Update UTMP about System Boot/Shutdown... Starting RPC bind portmap service... Starting Network Time Synchronization... Starting Permit User Sessions... Starting LKP bootstrap... Starting System Logging Service... Starting LSB: Execute the kexec -e command to reboot system... Starting OpenBSD Secure Shell server... Starting Login Service... Starting /etc/rc.local Compatibility... Starting LSB: Start and stop bmc-watchdog... [ 13.298892] rc.local[278]: mkdir: cannot create directory '/var/lock/lkp-bootstrap.lock': File exists Starting LSB: Load kernel image with kexec... [ 14.031902] Floppy drive(s): fd0 is 2.88M AMI BIOS [ 14.085572] FDC 0 is a S82078B [ 14.089069] parport_pc 00:04: reported by Plug and Play ACPI [ 14.110200] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 14.114218] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] LKP: HOSTNAME vm-snb-2G-442, MAC 52:54:00:12:34:56, kernel 5.2.0-rc1-00010-g6860279 1, serial console /dev/ttyS0 [ 14.318343] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 14.345146] cryptd: max_cpu_qlen set to 1000 [ 14.352023] libata version 3.00 loaded. [ 14.427699] ata_piix 0000:00:01.1: version 2.13 [ 14.468503] SSE version of gcm_enc/dec engaged. [ 14.504549] scsi host0: ata_piix To reproduce: # build kernel cd linux cp config-5.2.0-rc1-00010-g6860279 .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, Rong Chen

11 months, 2 weeks

1
0
0 / 0

[tracepoints] fa84b6f5ae: WARNING:at_arch/x86/kernel/static_call.c:#arch_static_call_transform

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: fa84b6f5ae4279cb8ba47a00ad52514ae428e051 ("tracepoints: Use static_call") https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git x86/static_call in testcase: rcutorture with following parameters: runtime: 300s test: cpuhotplug torture_type: tasks test-description: rcutorture is rcutorture kernel module load/unload test. test-url: https://www.kernel.org/doc/Documentation/RCU/torture.txt on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 3G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +-------------------------------------------------------------------------------------------+------------+------------+ | | 109e4788a5 | fa84b6f5ae | +-------------------------------------------------------------------------------------------+------------+------------+ | boot_successes | 5 | 0 | | boot_failures | 11 | 348 | | BUG:kernel_reboot-without-warning_in_test_stage | 3 | | | BUG:kernel_NULL_pointer_dereference,address | 6 | 107 | | Oops:#[##] | 8 | 131 | | RIP:smp_call_function_single | 2 | 75 | | Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 5 | 123 | | RIP:native_safe_halt | 4 | 101 | | Kernel_panic-not_syncing:Fatal_exception | 3 | 8 | | RIP:idle_cpu | 1 | | | RIP:__text_poke | 2 | 8 | | BUG:unable_to_handle_page_fault_for_address | 2 | 24 | | WARNING:at_arch/x86/kernel/static_call.c:#arch_static_call_transform | 0 | 348 | | RIP:arch_static_call_transform | 0 | 348 | | RIP:vprintk_emit | 0 | 3 | | RIP:native_write_msr | 0 | 17 | | Kernel_panic-not_syncing:stack-protector:Kernel_stack_is_corrupted_in:syscall_trace_enter | 0 | 4 | | RIP:text_poke_bp_batch | 0 | 1 | | RIP:native_send_call_func_single_ipi | 0 | 1 | | RIP:console_unlock | 0 | 2 | | RIP:llist_add_batch | 0 | 1 | +-------------------------------------------------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> [ 0.644628] WARNING: CPU: 0 PID: 1 at arch/x86/kernel/static_call.c:30 arch_static_call_transform+0x9c/0xb0 [ 0.645608] Modules linked in: [ 0.645608] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.2.0-rc4-00086-gfa84b6f #11 [ 0.645608] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 0.645608] RIP: 0010:arch_static_call_transform+0x9c/0xb0 [ 0.645608] Code: c9 ba 05 00 00 00 48 89 df 89 6c 24 04 e8 5c e0 ff ff eb b9 48 89 da 48 c7 c7 78 30 2d 8b c6 05 b6 b8 70 01 01 e8 b4 aa 0a 00 <0f> 0b eb 9f e8 4b ad 0a 00 90 90 90 90 90 90 90 90 90 90 90 48 89 [ 0.645608] RSP: 0000:ffffaf5a40637e10 EFLAGS: 00010286 [ 0.645608] RAX: 0000000000000000 RBX: ffffffff8bc2a655 RCX: 0000000000000000 [ 0.645608] RDX: 0000000000000001 RSI: ffffffff8be10161 RDI: 0000000000000246 [ 0.645608] RBP: ffffffff8a002090 R08: ffffffff8be10120 R09: 0000000000000041 [ 0.645608] R10: ffffaf5a40687d98 R11: 0000000000000001 R12: ffffffff8b614540 [ 0.645608] R13: ffffffff8bc2a655 R14: ffffffff8b614550 R15: ffffffff8b614550 [ 0.645608] FS: 0000000000000000(0000) GS:ffff8d6970200000(0000) knlGS:0000000000000000 [ 0.645608] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 0.645608] CR2: 00000000ffffffff CR3: 000000003f60a000 CR4: 00000000000406f0 [ 0.645608] Call Trace: [ 0.645608] static_call_init+0x133/0x17a [ 0.645608] ? stack_map_init+0x4e/0x4e [ 0.645608] do_one_initcall+0x44/0x1e4 [ 0.645608] ? proc_register+0xcd/0x130 [ 0.645608] kernel_init_freeable+0xf3/0x278 [ 0.645608] ? rest_init+0xd0/0xd0 [ 0.645608] kernel_init+0xa/0x110 [ 0.645608] ret_from_fork+0x35/0x40 [ 0.645608] ---[ end trace b52a5acc2a60c983 ]--- To reproduce: # build kernel cd linux cp config-5.2.0-rc4-00086-gfa84b6f .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, Rong Chen

11 months, 2 weeks

1
0
0 / 0

[xfs] b415391a5f: BUG:kernel_NULL_pointer_dereference, address

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: b415391a5f396d877cf8e01f71c1f6cdd918f916 ("xfs: move the log ioend workqueue to struct xlog") git://git.infradead.org/users/hch/xfs xfs-log-item-cleanup in testcase: xfstests with following parameters: disk: 4HDD fs: xfs test: generic-group8 test-description: xfstests is a regression test suite for xfs and other files ystems. test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +-------------------------------------------------------+------------+------------+ | | 9e1cd78798 | b415391a5f | +-------------------------------------------------------+------------+------------+ | boot_successes | 0 | 0 | | boot_failures | 1 | 22 | | BUG:kernel_reboot-without-warning_in_test_stage | 1 | 8 | | BUG:kernel_NULL_pointer_dereference,address | 0 | 9 | | Oops:#[##] | 0 | 9 | | RIP:_raw_spin_lock | 0 | 7 | | RIP:native_safe_halt | 0 | 12 | | Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 11 | | WARNING:at_kernel/workqueue.c:#__queue_work | 0 | 1 | | RIP:__queue_work | 0 | 5 | | BUG:kernel_hang_in_test_stage | 0 | 1 | | general_protection_fault:#[##] | 0 | 2 | | BUG:soft_lockup-CPU##stuck_for#s | 0 | 1 | | RIP:smp_call_function_single | 0 | 1 | | Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 1 | +-------------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <lkp(a)intel.com> [ 499.892181] BUG: kernel NULL pointer dereference, address: 0000000000000238 [ 499.895048] #PF: supervisor read access in kernel mode [ 499.897136] #PF: error_code(0x0000) - not-present page [ 499.899376] PGD 0 P4D 0 [ 499.900918] Oops: 0000 [#1] SMP PTI [ 499.902810] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 5.2.0-rc3-00017-gb415391 #1 [ 499.905457] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 499.908295] RIP: 0010:__queue_work+0x1b9/0x420 [ 499.910178] Code: 00 48 63 04 02 83 f8 ff 0f 84 a6 01 00 00 48 8d 84 c5 10 01 00 00 4c 89 f7 4c 8b 38 e8 10 e6 ff ff 48 85 c0 0f 85 d4 fe ff ff <49> 8b 07 e9 d5 fe ff ff 65 8b 05 48 23 b5 60 89 c0 48 0f a3 05 4e [ 499.917358] RSP: 0018:ffffb45ac03bce10 EFLAGS: 00010046 [ 499.919928] RAX: 0000000000000000 RBX: ffffffffa07a2a60 RCX: 0000000000000000 [ 499.923024] RDX: 0000000000027848 RSI: 000000007fffffff RDI: ffff970d600dd6f0 [ 499.926188] RBP: ffff970d60037a00 R08: 0000000000000000 R09: 0000000000000000 [ 499.929455] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 [ 499.932690] R13: 0000000000002000 R14: ffff970d600dd6f0 R15: 0000000000000238 [ 499.935262] FS: 0000000000000000(0000) GS:ffff970dab100000(0000) knlGS:0000000000000000 [ 499.938064] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 499.940345] CR2: 0000000000000238 CR3: 000000007e192000 CR4: 00000000000006e0 [ 499.942847] Call Trace: [ 499.945839] <IRQ> [ 499.947187] queue_work_on+0x34/0x40 [ 499.948860] blk_update_request+0x78/0x300 [ 499.950765] blk_mq_end_request+0x1a/0x120 [ 499.952548] blk_flush_complete_seq+0xe5/0x350 [ 499.954405] ? scsi_io_completion+0x85/0x4f0 [ 499.956230] flush_end_io+0x122/0x1b0 [ 499.957925] blk_done_softirq+0xa1/0xd0 [ 499.959741] __do_softirq+0xe3/0x311 [ 499.961398] irq_exit+0xdd/0xf0 [ 499.962948] call_function_single_interrupt+0xf/0x20 [ 499.964901] </IRQ> [ 499.966206] RIP: 0010:native_safe_halt+0xe/0x10 [ 499.968035] Code: eb bd 90 90 90 90 90 90 90 90 90 90 e9 07 00 00 00 0f 00 2d 86 de 5a 00 f4 c3 66 90 e9 07 00 00 00 0f 00 2d 76 de 5a 00 fb f4 <c3> 90 66 66 66 66 90 41 55 41 54 55 53 e8 00 05 6c ff 65 8b 2d 49 [ 499.974334] RSP: 0018:ffffb45ac0387eb8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff04 [ 499.977047] RAX: ffffffff9fe5c1a0 RBX: 0000000000000001 RCX: 0000000000000001 [ 499.979882] RDX: 0000000000000001 RSI: 0000000000000087 RDI: 0000000000000001 [ 499.982476] RBP: 0000000000000001 R08: 0000007c19195a48 R09: 0000007433f1918c [ 499.984974] R10: 0000000000000000 R11: 00000000000f4240 R12: 0000000000000000 [ 499.987550] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [ 499.990261] ? __sched_text_end+0x7/0x7 [ 499.992114] default_idle+0x1c/0x160 [ 499.993811] do_idle+0x1c4/0x280 [ 499.995383] cpu_startup_entry+0x19/0x20 [ 499.997119] start_secondary+0x184/0x1d0 [ 499.998858] secondary_startup_64+0xb6/0xc0 [ 500.000648] Modules linked in: xfs libcrc32c dm_mod sr_mod cdrom sg crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel ata_generic pata_acpi ppdev bochs_drm ttm drm_kms_helper snd_pcm syscopyarea sysfillrect sysimgblt fb_sys_fops snd_timer drm ata_piix snd aesni_intel crypto_simd libata cryptd glue_helper soundcore joydev pcspkr parport_pc serio_raw parport floppy i2c_piix4 ip_tables [ 500.015042] CR2: 0000000000000238 [ 500.017320] ---[ end trace bbfbad79f0ae8527 ]--- To reproduce: # build kernel cd linux cp config-5.2.0-rc3-00017-gb415391 .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage git clone https://github.com/intel/lkp-tests.git cd lkp-tests find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email Thanks, lkp

11 months, 2 weeks

1
0
0 / 0

[x86/alternatives] 0e2bc33d20: BUG:kernel_NULL_pointer_dereference, address

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: 0e2bc33d204ba5407465d09f8622077ad5739839 ("x86/alternatives: Teach text_poke_bp() to emulate instructions") https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git x86/static_call in testcase: boot on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +-------------------------------------------------------+------------+------------+ | | 68b46ce8b9 | 0e2bc33d20 | +-------------------------------------------------------+------------+------------+ | boot_successes | 91 | 64 | | boot_failures | 21 | 42 | | BUG:kernel_reboot-without-warning_in_test_stage | 21 | 11 | | BUG:kernel_NULL_pointer_dereference,address | 0 | 24 | | Oops:#[##] | 0 | 31 | | RIP:native_write_msr | 0 | 3 | | Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 29 | | RIP:native_safe_halt | 0 | 22 | | RIP:smp_call_function_single | 0 | 17 | | RIP:on_each_cpu | 0 | 1 | | BUG:unable_to_handle_page_fault_for_address | 0 | 7 | | RIP:__text_poke | 0 | 1 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 2 | +-------------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <lkp(a)intel.com> [ 2.869246] BUG: kernel NULL pointer dereference, address: 0000000000000000 [ 2.869246] #PF: supervisor instruction fetch in kernel mode [ 2.869246] #PF: error_code(0x0010) - not-present page [ 2.869246] PGD 0 P4D 0 [ 2.869246] Oops: 0010 [#1] SMP PTI [ 2.869246] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.2.0-rc4-00079-g0e2bc33 #1 [ 2.869246] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 2.869246] RIP: 0010:0x0 [ 2.869246] Code: Bad RIP value. [ 2.869246] RSP: 0000:ffffbcc1c0003f98 EFLAGS: 00010087 [ 2.869246] RAX: 0000000000005ba8 RBX: ffffffffb6114f5f RCX: ffff9eb8f00177f0 [ 2.869246] RDX: 0000000000000000 RSI: 00000000000f4240 RDI: 0000000000d8abf8 [ 2.869246] RBP: ffff9eb8ef4b3c00 R08: 0000000000000000 R09: 0000000000000000 [ 2.869246] R10: 0000000000000000 R11: 0000003b9aca0000 R12: 0000000000000000 [ 2.869246] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [ 2.869246] FS: 0000000000000000(0000) GS:ffff9eb8f0000000(0000) knlGS:0000000000000000 [ 2.869246] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 2.869246] CR2: ffffffffffffffd6 CR3: 000000006660a000 CR4: 00000000000406f0 [ 2.869246] Call Trace: [ 2.869246] <IRQ> [ 2.869246] update_process_times+0x1c/0x50 [ 2.869246] tick_periodic+0x27/0x80 [ 2.869246] tick_handle_periodic+0x20/0x60 [ 2.869246] smp_apic_timer_interrupt+0x6a/0x150 [ 2.869246] apic_timer_interrupt+0xf/0x20 [ 2.869246] </IRQ> [ 2.869246] RIP: 0010:smp_call_function_single+0xea/0x110 [ 2.869246] Code: 00 00 00 75 44 48 83 c4 48 41 5a 5d 49 8d 62 f8 c3 48 89 d1 48 89 f2 48 8d 75 b0 e8 60 fe ff ff 8b 55 c8 83 e2 01 74 0a f3 90 <8b> 55 c8 83 e2 01 75 f6 eb bb 8b 05 86 57 c7 01 85 c0 0f 85 76 ff [ 2.869246] RSP: 0000:ffffbcc1c0327d60 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff13 [ 2.869246] RAX: 0000000000000000 RBX: ffffffffb7dcc8d2 RCX: 0000000000000830 [ 2.869246] RDX: 0000000000000001 RSI: 00000000000000fb RDI: 0000000000000830 [ 2.869246] RBP: ffffbcc1c0327db0 R08: 0000000000000001 R09: 0000000000000001 [ 2.869246] R10: ffffbcc1c0327dd8 R11: 00000000000001a9 R12: 0000000000000000 [ 2.869246] R13: ffffffffb7dcc932 R14: 0000000000000000 R15: 0000000000000000 [ 2.869246] ? poke_int3_handler+0xe0/0xe0 [ 2.869246] ? cpumask_next_and+0x19/0x20 [ 2.869246] ? smp_call_function_many+0xa8/0x230 [ 2.869246] ? poke_int3_handler+0xe0/0xe0 [ 2.869246] ? on_each_cpu+0x36/0x70 [ 2.869246] on_each_cpu+0x36/0x70 [ 2.869246] text_poke_bp_batch+0x8b/0x150 [ 2.869246] ? kvm_init_platform+0x16/0x16 [ 2.869246] ? set_debug_rodata+0x11/0x11 [ 2.869246] arch_jump_label_transform_apply+0x2f/0x50 [ 2.869246] static_key_slow_inc_cpuslocked+0x8d/0xa0 [ 2.869246] static_key_slow_inc+0x16/0x20 [ 2.869246] activate_jump_labels+0x1a/0x32 [ 2.869246] do_one_initcall+0x46/0x214 [ 2.869246] kernel_init_freeable+0x1bb/0x278 [ 2.869246] ? rest_init+0xd0/0xd0 [ 2.869246] kernel_init+0xa/0x110 [ 2.869246] ret_from_fork+0x35/0x40 [ 2.869246] Modules linked in: [ 2.869246] CR2: 0000000000000000 [ 2.870246] BUG: kernel NULL pointer dereference, address: 0000000000000000 [ 2.869246] ---[ end trace 0d81c5e5ac5901cf ]--- To reproduce: # build kernel cd linux cp config-5.2.0-rc4-00079-g0e2bc33 .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, lkp

11 months, 2 weeks

1
0
0 / 0

dfd001e50c ("x86/hpet: Use common init for legacy clockevent"): BUG: kernel hang in boot stage

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is https://git.kernel.org/pub/scm/linux/kernel/git/tip/tip.git WIP.x86/hpet commit dfd001e50c13ef71659eac2e04971ad23218fc6b Author: Thomas Gleixner <tglx(a)linutronix.de> AuthorDate: Mon Jun 17 16:47:17 2019 +0200 Commit: Thomas Gleixner <tglx(a)linutronix.de> CommitDate: Tue Jun 18 01:14:50 2019 +0200 x86/hpet: Use common init for legacy clockevent Replace the static initialization of the legacy clockevent with runtime initialization utilizing the common init function as the last preparatory step to switch the legacy clockevent over to the channel 0 storage in hpet_base. Signed-off-by: Thomas Gleixner <tglx(a)linutronix.de> 5724812109 x86/hpet: Carve out shareable parts of init_one_hpet_msi_clockevent() dfd001e50c x86/hpet: Use common init for legacy clockevent 286b15db78 x86/hpet: Use channel for legacy clockevent storage dea8fc6983 Merge branch 'WIP.x86/hpet' +-------------------------------+------------+------------+------------+------------+ | | 5724812109 | dfd001e50c | 286b15db78 | dea8fc6983 | +-------------------------------+------------+------------+------------+------------+ | boot_successes | 29 | 0 | 0 | 4 | | boot_failures | 2 | 11 | 11 | 12 | | BUG:kernel_hang_in_test_stage | 1 | | | | | RIP:__clear_user | 1 | | | | | Mem-Info | 1 | | | | | BUG:kernel_hang_in_boot_stage | 0 | 11 | 11 | 12 | +-------------------------------+------------+------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <lkp(a)intel.com> [ 3.335199] Speculative Store Bypass: Vulnerable [ 3.335199] MDS: Vulnerable: Clear CPU buffers attempted, no microcode [ 3.335199] Freeing SMP alternatives memory: 40K [ 3.335199] Using local APIC timer interrupts. [ 3.335199] calibrating APIC timer ... BUG: kernel hang in boot stage # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD git bisect start dea8fc6983eeccb6aa2ebe1763a5723968b4e885 788a024921c48985939f8241c1ff862a7374d8f9 -- git bisect good c7b0e2162559ecce4fa2dbf58c6a874d6d9e6fc7 # 15:57 G 10 0 1 1 Merge branch 'x86/core' git bisect good f1155ae12ea4ac94d0535dc5bff02b6ba5d63625 # 15:58 G 10 0 1 12 Merge branch 'core/debugobjects' git bisect good 0cff5403802fb04241283d5f06bbc44444ea1b79 # 16:17 G 11 0 2 2 Merge branch 'perf/urgent' git bisect good 0ca41f69d78776b48e9cc081bd8a39333a5d1469 # 16:26 G 10 0 1 1 Merge branch 'perf/core' git bisect good d2afd8a6c0eb41460ad34fb73df666b0d9a2c1f8 # 16:42 G 10 0 1 1 Merge branch 'x86/fpu' git bisect good 35904ffcb3a538acf1cbc5e4edf473d471e1e001 # 17:21 G 10 0 1 1 Merge branch 'perf/core' git bisect good e804119845f22ff6d98c29852764e2ea70e671e2 # 18:09 G 11 0 2 2 x86/hpet: Make naming consistent git bisect good 7204a8bff110841b0bbad605fad37a25db90b387 # 18:41 G 10 0 3 3 x86/hpet: Rename variables to prepare for switching to channels git bisect good dcaa2b10cf79ec8b11b1e5a77f478c5204f089ec # 18:57 G 11 0 4 4 x86/hpet: Consolidate clockevent functions git bisect bad dfd001e50c13ef71659eac2e04971ad23218fc6b # 19:15 B 0 10 25 0 x86/hpet: Use common init for legacy clockevent git bisect good 5724812109139327ff1370d3783efb5b22e9cc39 # 19:24 G 11 0 2 2 x86/hpet: Carve out shareable parts of init_one_hpet_msi_clockevent() # first bad commit: [dfd001e50c13ef71659eac2e04971ad23218fc6b] x86/hpet: Use common init for legacy clockevent git bisect good 5724812109139327ff1370d3783efb5b22e9cc39 # 19:26 G 31 0 2 4 x86/hpet: Carve out shareable parts of init_one_hpet_msi_clockevent() # extra tests on HEAD of tip/master git bisect bad dea8fc6983eeccb6aa2ebe1763a5723968b4e885 # 19:26 B 0 12 47 3 Merge branch 'WIP.x86/hpet' # extra tests on tree/branch tip/WIP.x86/hpet git bisect bad 286b15db78dc9741a47d082016dfc5fbcc31bd46 # 19:48 B 0 2 17 0 x86/hpet: Use channel for legacy clockevent storage # extra tests on tree/branch tip/master git bisect bad dea8fc6983eeccb6aa2ebe1763a5723968b4e885 # 19:53 B 0 12 47 3 Merge branch 'WIP.x86/hpet' --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/pipermail/lkp Intel Corporation

11 months, 3 weeks

1
0
0 / 0

[IMA] f241bb1f42: BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h

by kernel test robot

FYI, we noticed the following commit (built with gcc-7): commit: f241bb1f42aa95a996c0c6ec8ff38e4306ec0281 ("IMA: support for per policy rule template formats") https://git.kernel.org/cgit/linux/kernel/git/zohar/linux-integrity.git next-queued-testing in testcase: rcutorture with following parameters: runtime: 300s test: default torture_type: srcud test-description: rcutorture is rcutorture kernel module load/unload test. test-url: https://www.kernel.org/doc/Documentation/RCU/torture.txt on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace): +----------------------------------------------------------------+------------+------------+ | | 2cd4737bc8 | f241bb1f42 | +----------------------------------------------------------------+------------+------------+ | boot_successes | 26 | 0 | | boot_failures | 12 | 7 | | BUG:soft_lockup-CPU##stuck_for#s | 1 | | | RIP:__do_softirq | 1 | | | RIP:generic_exec_single | 1 | | | Kernel_panic-not_syncing:softlockup:hung_tasks | 1 | | | BUG:kernel_reboot-without-warning_in_test_stage | 11 | | | BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 7 | +----------------------------------------------------------------+------------+------------+ If you fix the issue, kindly add following tag Reported-by: kernel test robot <rong.a.chen(a)intel.com> [ 7.918069] BUG: sleeping function called from invalid context at mm/slab.h:418 [ 7.920610] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: swapper/0 [ 7.923304] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.2.0-rc1-00007-gf241bb1 #2 [ 7.925336] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 7.927606] Call Trace: [ 7.928472] dump_stack+0x5c/0x7b [ 7.929481] ___might_sleep+0xf1/0x110 [ 7.930530] __kmalloc+0x186/0x220 [ 7.931507] template_desc_init_fields+0x1a3/0x2a0 [ 7.932919] ? inode_doinit_with_dentry+0x250/0x4e0 [ 7.934281] ? selinux_inode_getsecid+0x30/0x30 [ 7.936534] ? security_inode_alloc+0x4d/0x90 [ 7.938397] ? __d_instantiate+0x34/0xf0 [ 7.939905] ? set_debug_rodata+0x11/0x11 [ 7.941193] lookup_template_desc+0x7f/0xa0 [ 7.942369] ? hash_setup+0xa6/0xa6 [ 7.943381] ima_template_desc_current+0x2e/0x40 [ 7.944663] hash_setup+0xe/0xa6 [ 7.945873] init_ima+0x18/0x8a [ 7.946830] ? hash_setup+0xa6/0xa6 [ 7.947892] do_one_initcall+0x46/0x214 [ 7.949029] kernel_init_freeable+0x1c7/0x272 [ 7.950376] ? rest_init+0xd0/0xd0 [ 7.951404] kernel_init+0xa/0x110 [ 7.952531] ret_from_fork+0x35/0x40 [ 7.954077] ima: No TPM chip found, activating TPM-bypass! [ 7.955570] ima: Allocated hash algorithm: sha1 [ 7.956858] No architecture policies found [ 7.960449] evm: Initialising EVM extended attributes: [ 7.961870] evm: security.selinux [ 7.962906] evm: security.apparmor [ 7.963878] evm: security.ima [ 7.964769] evm: security.capability [ 7.965790] evm: HMAC attrs: 0x1 [ 7.968979] PM: Magic number: 7:687:860 [ 7.970343] rtc_cmos 00:00: setting system clock to 2019-06-18T00:52:12 UTC (1560819132) [ 10.026662] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX [ 10.029407] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 10.041086] Sending DHCP requests ., OK [ 10.046350] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15 [ 10.048145] IP-Config: Complete: [ 10.049163] device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2 [ 10.051802] host=vm-snb-quantal-x86_64-726, domain=, nis-domain=(none) [ 10.053678] bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath= [ 10.053680] nameserver0=10.0.2.3 [ 10.059316] Freeing unused decrypted memory: 2040K [ 10.062003] Freeing unused kernel image memory: 2504K [ 10.075092] Write protecting the kernel read-only data: 22528k [ 10.077905] Freeing unused kernel image memory: 2012K [ 10.080302] Freeing unused kernel image memory: 1924K [ 10.081764] rodata_test: all tests were successful [ 10.083218] Run /init as init process [ 10.090919] random: init: uninitialized urandom read (12 bytes read) [ 10.205441] random: mountall: uninitialized urandom read (12 bytes read) LKP: HOSTNAME vm-snb-quantal-x86_64-726, MAC 52:54:00:12:34:56, kernel 5.2.0-rc1-00007-gf241bb1 2, serial console /dev/ttyS0 [ 10.349986] udevd[268]: starting version 175 [ 10.546386] hostname: the specified hostname is invalid [ 10.546391] [ 10.552166] Kernel tests: Boot OK! [ 10.552170] [ 10.555238] /lkp/lkp/src/bin/run-lkp [ 10.555241] [ 10.564058] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 10.670662] RESULT_ROOT=/result/rcutorture/300s-default-srcud/vm-snb-quantal-x86_64/quantal-core-x86_64-2019-04-26.cgz/x86_64-rhel-7.6/gcc-7/f241bb1f42aa95a996c0c6ec8ff38e4306ec0281/3 [ 10.670667] [ 10.691373] libata version 3.00 loaded. [ 10.703581] job=/lkp/jobs/scheduled/vm-snb-quantal-x86_64-726/rcutorture-300s-default-srcud-quantal-core-x86_64-2019-0-20190618-5865-18zeb9r-1.yaml [ 10.703586] [ 10.811722] init: failsafe main process (418) killed by TERM signal [ 10.829247] result_service=raw_upload, RESULT_MNT=/inn/result, RESULT_ROOT=/inn/result/rcutorture/300s-default-srcud/vm-snb-quantal-x86_64/quantal-core-x86_64-2019-04-26.cgz/x86_64-rhel-7.6/gcc-7/f241bb1f42aa95a996c0c6ec8ff38e4306ec0281/3 [ 10.829252] [ 10.836612] ata_piix 0000:00:01.1: version 2.13 [ 10.848885] run-job /lkp/jobs/scheduled/vm-snb-quantal-x86_64-726/rcutorture-300s-default-srcud-quantal-core-x86_64-2019-0-20190618-5865-18zeb9r-1.yaml [ 10.848890] [ 10.865329] Floppy drive(s): fd0 is 2.88M AMI BIOS [ 10.868606] random: dd: uninitialized urandom read (4096 bytes read) [ 10.896214] FDC 0 is a S82078B [ 10.901731] init: rc-sysinit main process (442) killed by TERM signal [ 10.928934] scsi host0: ata_piix [ 10.957326] scsi host1: ata_piix [ 10.958503] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14 [ 10.960303] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15 [ 10.979394] parport_pc 00:04: reported by Plug and Play ACPI [ 10.981457] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] [ 10.981953] /usr/bin/wget -q --timeout=1800 --tries=1 --local-encoding=UTF-8 http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/jobs/sche... -O /dev/null [ 10.981958] [ 11.000909] ppdev: user-space parallel port driver [ 11.005896] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 0: 0xfd000000 -> 0xfdffffff [ 11.008124] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 2: 0xfebf0000 -> 0xfebf0fff [ 11.010168] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console [ 11.012995] Console: switching to colour dummy device 80x25 [ 11.013930] [drm] Found bochs VGA, ID 0xb0c0. [ 11.014714] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000. [ 11.015998] [TTM] Zone kernel: Available graphics memory: 1017352 KiB [ 11.017033] [TTM] Initializing pool allocator [ 11.017691] [TTM] Initializing DMA pool allocator [ 11.019198] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0 [ 11.023705] fbcon: bochs-drmdrmfb (fb0) is primary device [ 11.024753] Console: switching to colour frame buffer device 128x48 [ 11.029324] bochs-drm 0000:00:02.0: fb0: bochs-drmdrmfb frame buffer device [ 11.036999] target ucode: To reproduce: # build kernel cd linux cp config-5.2.0-rc1-00007-gf241bb1 .config make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 modules_prepare make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 SHELL=/bin/bash make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 bzImage git clone https://github.com/intel/lkp-tests.git cd lkp-tests bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email Thanks, Rong Chen

11 months, 3 weeks

1
0
0 / 0

2020

2019

2018

2017

2016

2015

2014

2013

LKP June 2019