June 2016 - LKP - Ml01.01.Org

[lkp] [SUNRPC] 818e8e4109: fsmark.files_per_sec -7.7% regression

by kernel test robot

FYI, we noticed a -7.7% regression of fsmark.files_per_sec due to commit: commit 818e8e4109d58cc55c884223a1d93426024d23d8 ("SUNRPC: RPC transport queue must be low latency") https://github.com/0day-ci/linux Trond-Myklebust/SUNRPC-Small-optimisation-of-client-receive/20160615-035043 in testcase: fsmark on test machine: ivb44: 48 threads Ivytown Ivy Bridge-EP with 64G memory with following parameters: cpufreq_governor=performance/disk=1BRD_48G/filesize=4M/fs2=nfsv4/fs=xfs/iterations=1x/nr_threads=64t/sync_method=NoSync/test_size=40G Details are as below: --------------------------------------------------------------------------------------------------> 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 ========================================================================================= compiler/cpufreq_governor/disk/filesize/fs2/fs/iterations/kconfig/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase: gcc-4.9/performance/1BRD_48G/4M/nfsv4/xfs/1x/x86_64-rhel/64t/debian-x86_64-2015-02-07.cgz/NoSync/ivb44/40G/fsmark commit: 6b25579f3a0c53fc63884d1487e3e8434670def4 818e8e4109d58cc55c884223a1d93426024d23d8 6b25579f3a0c53fc 818e8e4109d58cc55c884223a1 ---------------- -------------------------- %stddev %change %stddev \ | \ 10245512 ± 2% -54.4% 4675370 ± 2% fsmark.app_overhead 83.20 ± 0% -7.7% 76.80 ± 0% fsmark.files_per_sec 123.40 ± 0% +6.2% 131.06 ± 0% fsmark.time.elapsed_time 123.40 ± 0% +6.2% 131.06 ± 0% fsmark.time.elapsed_time.max 45128 ± 0% -68.3% 14323 ± 1% fsmark.time.involuntary_context_switches 37.50 ± 1% -17.3% 31.00 ± 0% fsmark.time.percent_of_cpu_this_job_got 46.66 ± 0% -11.5% 41.30 ± 0% fsmark.time.system_time 1488713 ± 0% -6.6% 1390819 ± 0% fsmark.time.voluntary_context_switches 280541 ± 2% +62.5% 455972 ± 0% softirqs.NET_RX 334642 ± 0% -5.7% 315581 ± 0% vmstat.io.bo 5821075 ± 0% +21.6% 7079435 ± 0% vmstat.memory.cache 46892 ± 0% -5.7% 44240 ± 0% vmstat.system.cs 21396 ± 28% -67.4% 6979 ± 11% latency_stats.max.call_rwsem_down_write_failed.filename_create.SyS_mkdir.entry_SYSCALL_64_fastpath 10905 ± 16% +474.2% 62621 ±165% latency_stats.max.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_lookup.[nfsv4].nfs4_proc_lookup_common.[nfsv4].nfs4_proc_lookup.[nfsv4].nfs_lookup_revalidate.nfs4_lookup_revalidate.lookup_dcache.__lookup_hash 3782146 ± 6% -70.4% 1120937 ± 2% latency_stats.sum.call_rwsem_down_write_failed.filename_create.SyS_mkdir.entry_SYSCALL_64_fastpath 208165 ± 10% -75.5% 51062 ± 13% latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_access.[nfsv4].nfs4_proc_access.[nfsv4].nfs_do_access.nfs_permission.__inode_permission.inode_permission.link_path_walk 7.46 ± 0% -6.5% 6.98 ± 0% turbostat.%Busy 158.75 ± 0% -5.8% 149.50 ± 0% turbostat.Avg_MHz 0.31 ± 6% -35.0% 0.20 ± 3% turbostat.CPU%c3 4.73 ± 0% -1.5% 4.65 ± 0% turbostat.RAMWatt 37004192 ± 2% -73.8% 9682574 ± 1% cpuidle.C1E-IVT.time 120363 ± 1% -52.9% 56683 ± 1% cpuidle.C1E-IVT.usage 23302776 ± 3% -24.2% 17659372 ± 2% cpuidle.C3-IVT.time 82331 ± 1% -21.0% 65040 ± 0% cpuidle.C3-IVT.usage 36963 ± 1% -25.0% 27736 ± 2% cpuidle.POLL.usage 5420047 ± 1% +23.2% 6677171 ± 0% meminfo.Cached 3081 ± 6% -46.5% 1648 ± 12% meminfo.Dirty 5358458 ± 1% +23.3% 6605440 ± 0% meminfo.Inactive 5349323 ± 1% +23.3% 6596293 ± 0% meminfo.Inactive(file) 3093 ± 13% -56.6% 1344 ± 1% meminfo.NFS_Unstable 145243 ± 1% +15.9% 168335 ± 0% meminfo.SReclaimable 44381 ± 3% +22.4% 54339 ± 2% meminfo.Unevictable 2696 ± 6% -49.5% 1362 ± 7% meminfo.Writeback 615701 ± 1% +25.7% 773764 ± 0% slabinfo.buffer_head.active_objs 15787 ± 1% +25.7% 19840 ± 0% slabinfo.buffer_head.active_slabs 615714 ± 1% +25.7% 773776 ± 0% slabinfo.buffer_head.num_objs 15787 ± 1% +25.7% 19840 ± 0% slabinfo.buffer_head.num_slabs 57899 ± 2% +21.8% 70515 ± 1% slabinfo.radix_tree_node.active_objs 1037 ± 2% +21.3% 1258 ± 1% slabinfo.radix_tree_node.active_slabs 58118 ± 2% +21.3% 70515 ± 1% slabinfo.radix_tree_node.num_objs 1037 ± 2% +21.3% 1258 ± 1% slabinfo.radix_tree_node.num_slabs 1266 ± 2% -10.9% 1128 ± 3% slabinfo.scsi_data_buffer.active_objs 1266 ± 2% -10.9% 1128 ± 3% slabinfo.scsi_data_buffer.num_objs 760.25 ± 2% +22.2% 929.00 ± 1% proc-vmstat.kswapd_low_wmark_hit_quickly 760.00 ± 4% -44.9% 418.50 ± 8% proc-vmstat.nr_dirty 1355408 ± 1% +23.2% 1669485 ± 0% proc-vmstat.nr_file_pages 1337536 ± 1% +23.3% 1649068 ± 0% proc-vmstat.nr_inactive_file 36313 ± 1% +15.9% 42082 ± 0% proc-vmstat.nr_slab_reclaimable 11094 ± 3% +22.4% 13584 ± 2% proc-vmstat.nr_unevictable 756.00 ± 13% -55.8% 334.25 ± 2% proc-vmstat.nr_unstable 681.75 ± 9% -48.2% 353.25 ± 5% proc-vmstat.nr_writeback 1488 ± 34% -90.8% 136.25 ±173% proc-vmstat.pgscan_direct_dma32 34551 ± 22% -91.0% 3113 ±173% proc-vmstat.pgscan_direct_normal 1004 ± 49% -86.4% 136.25 ±173% proc-vmstat.pgsteal_direct_dma32 7921 ± 64% -83.3% 1322 ±173% proc-vmstat.pgsteal_direct_normal 9.06e+08 ± 2% -10.8% 8.078e+08 ± 4% perf-stat.L1-dcache-prefetch-misses 4.514e+09 ± 0% +5.6% 4.766e+09 ± 1% perf-stat.L1-icache-load-misses 1.53e+09 ± 5% -20.4% 1.218e+09 ± 0% perf-stat.LLC-load-misses 2.351e+09 ± 3% +10.2% 2.592e+09 ± 2% perf-stat.LLC-prefetches 2.48e+09 ± 3% +10.7% 2.746e+09 ± 2% perf-stat.LLC-store-misses 3.651e+09 ± 1% +5.6% 3.855e+09 ± 2% perf-stat.LLC-stores 6.288e+10 ± 4% +11.5% 7.01e+10 ± 0% perf-stat.branch-instructions 1.176e+09 ± 2% +11.5% 1.311e+09 ± 4% perf-stat.branch-load-misses 5.597e+10 ± 10% +13.1% 6.332e+10 ± 6% perf-stat.branch-loads 1.241e+09 ± 2% +11.6% 1.386e+09 ± 4% perf-stat.branch-misses 1.038e+11 ± 2% +9.3% 1.134e+11 ± 2% perf-stat.dTLB-loads 92930638 ± 1% +4.9% 97490872 ± 2% perf-stat.iTLB-load-misses 277756 ± 0% +5.3% 292450 ± 0% perf-stat.minor-faults 1.138e+09 ± 10% -26.1% 8.414e+08 ± 5% perf-stat.node-loads 6.039e+08 ± 9% -18.9% 4.896e+08 ± 4% perf-stat.node-prefetches 5.084e+08 ± 2% +25.3% 6.372e+08 ± 5% perf-stat.node-store-misses 277802 ± 0% +5.3% 292431 ± 0% perf-stat.page-faults 1398 ± 12% -49.8% 701.75 ± 12% numa-meminfo.node0.Dirty 2673371 ± 2% +21.0% 3233672 ± 6% numa-meminfo.node0.FilePages 2642305 ± 2% +21.0% 3197794 ± 6% numa-meminfo.node0.Inactive 2639728 ± 1% +21.1% 3197516 ± 6% numa-meminfo.node0.Inactive(file) 4995759 ± 2% +21.7% 6082060 ± 2% numa-meminfo.node0.MemUsed 1521 ± 5% -59.6% 614.25 ± 11% numa-meminfo.node0.NFS_Unstable 21760 ± 3% +23.2% 26805 ± 0% numa-meminfo.node0.Unevictable 1438 ± 17% -51.5% 698.00 ± 13% numa-meminfo.node0.Writeback 3517 ± 61% +56.7% 5511 ± 15% numa-meminfo.node1.AnonHugePages 1634 ± 9% -46.2% 879.00 ± 9% numa-meminfo.node1.Dirty 2726731 ± 1% +25.8% 3429717 ± 5% numa-meminfo.node1.FilePages 2696295 ± 1% +25.9% 3393847 ± 5% numa-meminfo.node1.Inactive 2689711 ± 1% +25.8% 3384959 ± 5% numa-meminfo.node1.Inactive(file) 4917935 ± 2% +23.5% 6072040 ± 2% numa-meminfo.node1.MemUsed 1384 ± 2% -48.5% 713.75 ± 21% numa-meminfo.node1.NFS_Unstable 69455 ± 1% +22.6% 85179 ± 7% numa-meminfo.node1.SReclaimable 120279 ± 3% +14.6% 137894 ± 8% numa-meminfo.node1.Slab 21762 ± 3% +23.3% 26831 ± 0% numa-meminfo.node1.Unevictable 1702 ± 9% -58.3% 709.50 ± 11% numa-meminfo.node1.Writeback 975493 ± 2% +21.0% 1180348 ± 5% numa-vmstat.node0.nr_dirtied 380.50 ± 9% -55.2% 170.50 ± 13% numa-vmstat.node0.nr_dirty 668385 ± 2% +20.9% 808309 ± 6% numa-vmstat.node0.nr_file_pages 659973 ± 1% +21.1% 799269 ± 6% numa-vmstat.node0.nr_inactive_file 5439 ± 3% +23.2% 6701 ± 0% numa-vmstat.node0.nr_unevictable 369.25 ± 7% -59.4% 150.00 ± 9% numa-vmstat.node0.nr_unstable 379.00 ± 15% -56.6% 164.50 ± 11% numa-vmstat.node0.nr_writeback 974763 ± 2% +21.1% 1180042 ± 5% numa-vmstat.node0.nr_written 1655473 ± 2% +21.5% 2011897 ± 6% numa-vmstat.node0.numa_hit 1655473 ± 2% +21.5% 2011897 ± 6% numa-vmstat.node0.numa_local 967615 ± 3% +28.0% 1238876 ± 5% numa-vmstat.node1.nr_dirtied 367.00 ± 0% -40.3% 219.00 ± 9% numa-vmstat.node1.nr_dirty 681556 ± 1% +25.8% 857306 ± 5% numa-vmstat.node1.nr_file_pages 672299 ± 1% +25.9% 846118 ± 5% numa-vmstat.node1.nr_inactive_file 17358 ± 1% +22.7% 21291 ± 7% numa-vmstat.node1.nr_slab_reclaimable 5440 ± 3% +23.3% 6708 ± 0% numa-vmstat.node1.nr_unevictable 348.75 ± 10% -45.3% 190.75 ± 17% numa-vmstat.node1.nr_unstable 389.75 ± 13% -48.5% 200.75 ± 4% numa-vmstat.node1.nr_writeback 966871 ± 3% +28.1% 1238490 ± 5% numa-vmstat.node1.nr_written 1594506 ± 4% +27.2% 2028330 ± 5% numa-vmstat.node1.numa_hit 1594505 ± 4% +27.2% 2028329 ± 5% numa-vmstat.node1.numa_local 976.82 ± 1% -26.3% 719.87 ± 1% sched_debug.cfs_rq:/.exec_clock.avg 2411 ± 3% -12.9% 2101 ± 3% sched_debug.cfs_rq:/.exec_clock.max 58.45 ± 11% -60.9% 22.88 ± 8% sched_debug.cfs_rq:/.exec_clock.min 887.20 ± 1% -20.3% 706.71 ± 0% sched_debug.cfs_rq:/.exec_clock.stddev 330.47 ± 5% -60.8% 129.43 ± 6% sched_debug.cfs_rq:/.load_avg.avg 1765 ± 7% -56.7% 763.92 ± 9% sched_debug.cfs_rq:/.load_avg.max 456.70 ± 7% -56.4% 199.20 ± 7% sched_debug.cfs_rq:/.load_avg.stddev 49.53 ± 14% -33.3% 33.04 ± 10% sched_debug.cfs_rq:/.runnable_load_avg.avg 140.97 ± 23% -26.0% 104.31 ± 4% sched_debug.cfs_rq:/.runnable_load_avg.stddev 746.08 ± 5% -12.9% 649.58 ± 2% sched_debug.cfs_rq:/.util_avg.max 153.35 ± 6% -16.3% 128.31 ± 3% sched_debug.cfs_rq:/.util_avg.stddev 36.90 ± 11% -21.6% 28.93 ± 13% sched_debug.cpu.cpu_load[0].avg 108.16 ± 5% -10.7% 96.61 ± 6% sched_debug.cpu.cpu_load[0].stddev 67.83 ± 6% -22.6% 52.51 ± 6% sched_debug.cpu.cpu_load[1].avg 133.54 ± 7% -17.7% 109.90 ± 12% sched_debug.cpu.cpu_load[1].stddev 62.46 ± 7% -23.9% 47.54 ± 3% sched_debug.cpu.cpu_load[2].avg 632.17 ± 11% -14.3% 541.67 ± 9% sched_debug.cpu.cpu_load[2].max 119.72 ± 7% -18.3% 97.77 ± 7% sched_debug.cpu.cpu_load[2].stddev 55.54 ± 7% -24.7% 41.80 ± 2% sched_debug.cpu.cpu_load[3].avg 589.17 ± 6% -17.2% 488.00 ± 4% sched_debug.cpu.cpu_load[3].max 107.89 ± 5% -19.1% 87.30 ± 4% sched_debug.cpu.cpu_load[3].stddev 50.93 ± 8% -26.0% 37.71 ± 2% sched_debug.cpu.cpu_load[4].avg 543.75 ± 4% -16.7% 453.08 ± 4% sched_debug.cpu.cpu_load[4].max 99.09 ± 3% -18.6% 80.63 ± 3% sched_debug.cpu.cpu_load[4].stddev 0.00 ± 44% -43.6% 0.00 ± 13% sched_debug.cpu.next_balance.stddev 2415 ± 6% -11.7% 2133 ± 4% sched_debug.cpu.nr_load_updates.stddev 37473 ± 1% -27.5% 27179 ± 0% sched_debug.cpu.nr_switches.avg 87510 ± 7% -23.2% 67204 ± 6% sched_debug.cpu.nr_switches.max 1512 ± 15% -58.6% 625.33 ± 8% sched_debug.cpu.nr_switches.min 35187 ± 1% -24.7% 26504 ± 1% sched_debug.cpu.nr_switches.stddev 12.59 ± 10% -27.2% 9.16 ± 8% sched_debug.cpu.nr_uninterruptible.stddev 36793 ± 1% -28.0% 26494 ± 0% sched_debug.cpu.sched_count.avg 90630 ± 4% -22.0% 70672 ± 9% sched_debug.cpu.sched_count.max 1189 ± 16% -71.9% 333.92 ± 13% sched_debug.cpu.sched_count.min 35241 ± 1% -24.8% 26503 ± 1% sched_debug.cpu.sched_count.stddev 16975 ± 1% -28.4% 12159 ± 1% sched_debug.cpu.sched_goidle.avg 39073 ± 6% -22.9% 30133 ± 6% sched_debug.cpu.sched_goidle.max 517.50 ± 17% -75.5% 126.83 ± 13% sched_debug.cpu.sched_goidle.min 16091 ± 1% -25.3% 12019 ± 1% sched_debug.cpu.sched_goidle.stddev 18581 ± 1% -27.9% 13391 ± 1% sched_debug.cpu.ttwu_count.avg 43020 ± 5% -25.7% 31949 ± 7% sched_debug.cpu.ttwu_count.max 919.08 ± 15% -77.3% 208.75 ± 2% sched_debug.cpu.ttwu_count.min 16996 ± 1% -23.8% 12956 ± 1% sched_debug.cpu.ttwu_count.stddev 1647 ± 0% -18.3% 1347 ± 0% sched_debug.cpu.ttwu_local.avg 3739 ± 3% -14.2% 3207 ± 3% sched_debug.cpu.ttwu_local.max 120.75 ± 4% -37.2% 75.83 ± 3% sched_debug.cpu.ttwu_local.min 1476 ± 1% -13.7% 1273 ± 1% sched_debug.cpu.ttwu_local.stddev 1.05 ± 4% -12.8% 0.92 ± 2% perf-profile.cycles-pp.__filemap_fdatawait_range.filemap_fdatawait_range.filemap_write_and_wait_range.xfs_file_fsync.vfs_fsync_range 12.69 ± 1% -25.3% 9.47 ± 1% perf-profile.cycles-pp.__filemap_fdatawrite_range.filemap_write_and_wait_range.xfs_file_fsync.vfs_fsync_range.nfsd_commit 10.17 ± 5% -17.3% 8.41 ± 5% perf-profile.cycles-pp.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write 1.70 ± 2% +17.8% 2.00 ± 5% perf-profile.cycles-pp.__rpc_execute.rpc_async_schedule.process_one_work.worker_thread.kthread 10.41 ± 5% -18.1% 8.52 ± 4% perf-profile.cycles-pp.__vfs_write.vfs_write.sys_write.entry_SYSCALL_64_fastpath 1.25 ± 6% +52.7% 1.90 ± 5% perf-profile.cycles-pp.apic_timer_interrupt.cpuidle_enter.call_cpuidle.cpu_startup_entry.start_secondary 7.69 ± 2% -30.9% 5.31 ± 3% perf-profile.cycles-pp.brd_do_bvec.brd_make_request.generic_make_request.submit_bio.xfs_add_to_ioend 2.61 ± 2% -28.3% 1.87 ± 5% perf-profile.cycles-pp.brd_do_bvec.brd_make_request.generic_make_request.submit_bio.xfs_submit_ioend.isra.17 4.68 ± 1% -19.5% 3.77 ± 2% perf-profile.cycles-pp.brd_insert_page.part.4.brd_do_bvec.brd_make_request.generic_make_request.submit_bio 7.80 ± 2% -30.5% 5.42 ± 3% perf-profile.cycles-pp.brd_make_request.generic_make_request.submit_bio.xfs_add_to_ioend.xfs_do_writepage 2.64 ± 2% -26.3% 1.94 ± 2% perf-profile.cycles-pp.brd_make_request.generic_make_request.submit_bio.xfs_submit_ioend.isra.17.xfs_vm_writepages 3.15 ± 7% +121.1% 6.96 ± 2% perf-profile.cycles-pp.call_rwsem_down_write_failed.down_write.xfs_file_buffered_aio_write.xfs_file_write_iter.do_iter_readv_writev 1.31 ± 4% +26.0% 1.66 ± 5% perf-profile.cycles-pp.call_transmit.__rpc_execute.rpc_async_schedule.process_one_work.worker_thread 3.58 ± 7% -16.2% 3.00 ± 3% perf-profile.cycles-pp.copy_page_to_iter.skb_copy_datagram_iter.tcp_recvmsg.inet_recvmsg.sock_recvmsg 3.89 ± 5% -13.6% 3.36 ± 5% perf-profile.cycles-pp.copy_user_enhanced_fast_string.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write 13.17 ± 6% +26.5% 16.66 ± 1% perf-profile.cycles-pp.do_iter_readv_writev.do_readv_writev.vfs_writev.nfsd_vfs_write.nfsd4_write 13.21 ± 6% +26.3% 16.69 ± 1% perf-profile.cycles-pp.do_readv_writev.vfs_writev.nfsd_vfs_write.nfsd4_write.nfsd4_proc_compound 12.68 ± 1% -25.3% 9.47 ± 1% perf-profile.cycles-pp.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.xfs_file_fsync.vfs_fsync_range 3.15 ± 7% +120.8% 6.96 ± 2% perf-profile.cycles-pp.down_write.xfs_file_buffered_aio_write.xfs_file_write_iter.do_iter_readv_writev.do_readv_writev 16.75 ± 3% -13.9% 14.42 ± 4% perf-profile.cycles-pp.entry_SYSCALL_64_fastpath 1.07 ± 4% -12.9% 0.93 ± 3% perf-profile.cycles-pp.filemap_fdatawait_range.filemap_write_and_wait_range.xfs_file_fsync.vfs_fsync_range.nfsd_commit 3.51 ± 4% -7.4% 3.25 ± 5% perf-profile.cycles-pp.filemap_write_and_wait_range.nfs_file_fsync.vfs_fsync_range.vfs_fsync.nfs4_file_flush 13.75 ± 1% -24.3% 10.41 ± 1% perf-profile.cycles-pp.filemap_write_and_wait_range.xfs_file_fsync.vfs_fsync_range.nfsd_commit.nfsd4_commit 10.23 ± 5% -17.4% 8.46 ± 5% perf-profile.cycles-pp.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.sys_write 7.84 ± 1% -30.3% 5.47 ± 3% perf-profile.cycles-pp.generic_make_request.submit_bio.xfs_add_to_ioend.xfs_do_writepage.write_cache_pages 2.64 ± 2% -26.0% 1.95 ± 2% perf-profile.cycles-pp.generic_make_request.submit_bio.xfs_submit_ioend.isra.17.xfs_vm_writepages.do_writepages 10.05 ± 5% -17.8% 8.26 ± 5% perf-profile.cycles-pp.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write 4.65 ± 7% -13.6% 4.02 ± 2% perf-profile.cycles-pp.inet_recvmsg.sock_recvmsg.kernel_recvmsg.svc_recvfrom.isra.9.svc_tcp_recvfrom 1.00 ± 4% +27.6% 1.28 ± 8% perf-profile.cycles-pp.inet_sendpage.xs_sendpages.xs_tcp_send_request.xprt_transmit.call_transmit 4.66 ± 7% -13.3% 4.04 ± 2% perf-profile.cycles-pp.kernel_recvmsg.svc_recvfrom.isra.9.svc_tcp_recvfrom.svc_recv.nfsd 3.45 ± 6% -16.4% 2.89 ± 4% perf-profile.cycles-pp.memcpy_erms.copy_page_to_iter.skb_copy_datagram_iter.tcp_recvmsg.inet_recvmsg 10.39 ± 5% -18.0% 8.52 ± 5% perf-profile.cycles-pp.nfs_file_write.__vfs_write.vfs_write.sys_write.entry_SYSCALL_64_fastpath 1.85 ± 3% -15.4% 1.57 ± 6% perf-profile.cycles-pp.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter 3.80 ± 4% -24.3% 2.88 ± 7% perf-profile.cycles-pp.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write 13.81 ± 1% -24.0% 10.50 ± 1% perf-profile.cycles-pp.nfsd4_commit.nfsd4_proc_compound.nfsd_dispatch.svc_process_common.svc_process 13.25 ± 6% +26.2% 16.73 ± 1% perf-profile.cycles-pp.nfsd4_write.nfsd4_proc_compound.nfsd_dispatch.svc_process_common.svc_process 13.81 ± 1% -24.0% 10.50 ± 1% perf-profile.cycles-pp.nfsd_commit.nfsd4_commit.nfsd4_proc_compound.nfsd_dispatch.svc_process_common 13.22 ± 6% +26.3% 16.70 ± 1% perf-profile.cycles-pp.nfsd_vfs_write.nfsd4_write.nfsd4_proc_compound.nfsd_dispatch.svc_process_common 0.00 ± -1% +Inf% 1.56 ± 3% perf-profile.cycles-pp.osq_lock.rwsem_optimistic_spin.rwsem_down_write_failed.call_rwsem_down_write_failed.down_write 1.71 ± 3% +17.8% 2.02 ± 4% perf-profile.cycles-pp.rpc_async_schedule.process_one_work.worker_thread.kthread.ret_from_fork 3.15 ± 7% +121.0% 6.96 ± 2% perf-profile.cycles-pp.rwsem_down_write_failed.call_rwsem_down_write_failed.down_write.xfs_file_buffered_aio_write.xfs_file_write_iter 3.15 ± 7% +121.0% 6.96 ± 2% perf-profile.cycles-pp.rwsem_optimistic_spin.rwsem_down_write_failed.call_rwsem_down_write_failed.down_write.xfs_file_buffered_aio_write 2.73 ± 6% +93.7% 5.29 ± 2% perf-profile.cycles-pp.rwsem_spin_on_owner.rwsem_optimistic_spin.rwsem_down_write_failed.call_rwsem_down_write_failed.down_write 3.81 ± 6% -15.5% 3.22 ± 3% perf-profile.cycles-pp.skb_copy_datagram_iter.tcp_recvmsg.inet_recvmsg.sock_recvmsg.kernel_recvmsg 1.19 ± 7% +54.9% 1.85 ± 5% perf-profile.cycles-pp.smp_apic_timer_interrupt.apic_timer_interrupt.cpuidle_enter.call_cpuidle.cpu_startup_entry 4.66 ± 7% -13.3% 4.04 ± 2% perf-profile.cycles-pp.sock_recvmsg.kernel_recvmsg.svc_recvfrom.isra.9.svc_tcp_recvfrom.svc_recv 7.84 ± 1% -30.2% 5.47 ± 3% perf-profile.cycles-pp.submit_bio.xfs_add_to_ioend.xfs_do_writepage.write_cache_pages.xfs_vm_writepages 2.64 ± 2% -26.0% 1.95 ± 2% perf-profile.cycles-pp.submit_bio.xfs_submit_ioend.isra.17.xfs_vm_writepages.do_writepages.__filemap_fdatawrite_range 4.67 ± 7% -13.3% 4.05 ± 2% perf-profile.cycles-pp.svc_recvfrom.isra.9.svc_tcp_recvfrom.svc_recv.nfsd.kthread 10.51 ± 5% -17.7% 8.65 ± 5% perf-profile.cycles-pp.sys_write.entry_SYSCALL_64_fastpath 4.64 ± 7% -13.8% 4.00 ± 2% perf-profile.cycles-pp.tcp_recvmsg.inet_recvmsg.sock_recvmsg.kernel_recvmsg.svc_recvfrom.isra.9 0.96 ± 4% +29.1% 1.24 ± 7% perf-profile.cycles-pp.tcp_sendpage.inet_sendpage.xs_sendpages.xs_tcp_send_request.xprt_transmit 13.81 ± 1% -24.1% 10.48 ± 1% perf-profile.cycles-pp.vfs_fsync_range.nfsd_commit.nfsd4_commit.nfsd4_proc_compound.nfsd_dispatch 10.49 ± 5% -17.9% 8.62 ± 5% perf-profile.cycles-pp.vfs_write.sys_write.entry_SYSCALL_64_fastpath 13.21 ± 6% +26.3% 16.69 ± 1% perf-profile.cycles-pp.vfs_writev.nfsd_vfs_write.nfsd4_write.nfsd4_proc_compound.nfsd_dispatch 10.03 ± 1% -25.1% 7.51 ± 1% perf-profile.cycles-pp.write_cache_pages.xfs_vm_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range 8.03 ± 2% -30.2% 5.61 ± 3% perf-profile.cycles-pp.xfs_add_to_ioend.xfs_do_writepage.write_cache_pages.xfs_vm_writepages.do_writepages 9.43 ± 1% -26.4% 6.93 ± 2% perf-profile.cycles-pp.xfs_do_writepage.write_cache_pages.xfs_vm_writepages.do_writepages.__filemap_fdatawrite_range 13.17 ± 6% +26.5% 16.65 ± 1% perf-profile.cycles-pp.xfs_file_buffered_aio_write.xfs_file_write_iter.do_iter_readv_writev.do_readv_writev.vfs_writev 13.80 ± 0% -24.1% 10.48 ± 1% perf-profile.cycles-pp.xfs_file_fsync.vfs_fsync_range.nfsd_commit.nfsd4_commit.nfsd4_proc_compound 13.17 ± 6% +26.5% 16.66 ± 1% perf-profile.cycles-pp.xfs_file_write_iter.do_iter_readv_writev.do_readv_writev.vfs_writev.nfsd_vfs_write 2.65 ± 2% -26.0% 1.96 ± 2% perf-profile.cycles-pp.xfs_submit_ioend.isra.17.xfs_vm_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range 12.68 ± 1% -25.3% 9.47 ± 1% perf-profile.cycles-pp.xfs_vm_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.xfs_file_fsync 1.26 ± 4% +27.8% 1.61 ± 5% perf-profile.cycles-pp.xprt_transmit.call_transmit.__rpc_execute.rpc_async_schedule.process_one_work 1.24 ± 3% +28.0% 1.59 ± 5% perf-profile.cycles-pp.xs_sendpages.xs_tcp_send_request.xprt_transmit.call_transmit.__rpc_execute 1.24 ± 3% +28.4% 1.59 ± 5% perf-profile.cycles-pp.xs_tcp_send_request.xprt_transmit.call_transmit.__rpc_execute.rpc_async_schedule fsmark.time.system_time 48 ++---------------------------------------------------------------------+ | *. * *. *.. | 47 ++ + * .. + .* .. * + * .*.. .* 46 ++ .* + .* *. .*..* + .* + .* + .* * | *.*. *..*.* * *..*.* *.*..* * | 45 ++ | | | 44 ++ | | | 43 ++ | 42 ++ | | O | 41 ++ O O O O O O O O O O O O O O O O O O O | O O O O O O | 40 ++-----O---------------------------------------------------------------+ fsmark.time.percent_of_cpu_this_job_got 38 ++-----*-*-----------*----------*-----------*-*----------*--*-----*----* | + + .. + + + .. + + + + +| 37 *+*..* *..*.*.* *.*.*..* *..*.*.* *.*..*.* *.* * | 36 ++ | | | 35 ++ | | | 34 ++ | | | 33 ++ | 32 ++ | | | 31 ++ O O O O O O O O O O O O O O O O O O O O O O | | | 30 O+O--O-O-O-------------------------------------------------------------+ fsmark.time.elapsed_time 134 ++---------O----------------------------------------------------------+ O O O O O O | 132 ++ | | O O | | O O O O O O O O O O O O O O O O O | 130 ++ O | | | 128 ++ | | | 126 ++ | | | | *.. *.. *. | 124 ++ *.*. .*.. .*..*. + *.*. + *.*.. .*.*.. .* * *.*..*.*.* *.*.* *.* *.*..* *.* * | 122 ++--------------------------------------------------------------------+ fsmark.time.elapsed_time.max 134 ++---------O----------------------------------------------------------+ O O O O O O | 132 ++ | | O O | | O O O O O O O O O O O O O O O O O | 130 ++ O | | | 128 ++ | | | 126 ++ | | | | *.. *.. *. | 124 ++ *.*. .*.. .*..*. + *.*. + *.*.. .*.*.. .* * *.*..*.*.* *.*.* *.* *.*..* *.* * | 122 ++--------------------------------------------------------------------+ fsmark.time.involuntary_context_switches 50000 ++------------------------------------------------------------------+ | | 45000 *+*.*..*.*.*.*.*..*.*.*.*.*..*.*.*.*.*.*..*.*.*.*.*..*.*.*.*.*..*.*.* 40000 ++ | | | 35000 ++ | | | 30000 ++ | | | 25000 ++ | 20000 ++ | | | 15000 ++ O O O O O O O O O O O O O O O O O O O O O | O O O O O O | 10000 ++------------------------------------------------------------------+ fsmark.files_per_sec 84 ++---------------------------------------------------------------------+ *.*..*.*.*.*..*.*.*..*.*.*.*..*.*.*..*.*.*..*.*.*.*..*.*.*..*.*.*.*..*.* 83 ++ | 82 ++ | | | 81 ++ | | | 80 ++ | | | 79 ++ | 78 ++ | | | 77 ++ | O O O O O O O O O O O O O O O O O O O O O O O O O O O | 76 ++---------------------------------------------------------------------+ fsmark.app_overhead 1.6e+07 ++----------------------------------------------------------------+ | * | 1.4e+07 ++ : | | : : | | : : | 1.2e+07 ++ : : | |.*.*. .* : .*. .*. .*.*. .*. .*.*. .*.. .*.| 1e+07 *+ *..* *.* *.*..*.*.* * *..* * *.* *.* * | | 8e+06 ++ | | | | | 6e+06 ++ | O O O O O O O O O O O O O O O O O O O O O O O O O O O | 4e+06 ++----------------------------------------------------------------+ [*] 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, Xiaolong

4 years, 8 months

1
0
0 / 0

[lkp] [mm] 5c0a85fad9: unixbench.score -6.3% regression

by kernel test robot

FYI, we noticed a -6.3% regression of unixbench.score due to commit: commit 5c0a85fad949212b3e059692deecdeed74ae7ec7 ("mm: make faultaround produce old ptes") https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master in testcase: unixbench on test machine: lituya: 16 threads Haswell High-end Desktop (i7-5960X 3.0G) with 16G memory with following parameters: cpufreq_governor=performance/nr_task=1/test=shell8 Details are as below: --------------------------------------------------------------------------------------------------> ========================================================================================= compiler/cpufreq_governor/kconfig/nr_task/rootfs/tbox_group/test/testcase: gcc-4.9/performance/x86_64-rhel/1/debian-x86_64-2015-02-07.cgz/lituya/shell8/unixbench commit: 4b50bcc7eda4d3cc9e3f2a0aa60e590fedf728c5 5c0a85fad949212b3e059692deecdeed74ae7ec7 4b50bcc7eda4d3cc 5c0a85fad949212b3e059692de ---------------- -------------------------- fail:runs %reproduction fail:runs | | | 3:4 -75% :4 kmsg.DHCP/BOOTP:Reply_not_for_us,op[#]xid[#] %stddev %change %stddev \ | \ 14321 ± 0% -6.3% 13425 ± 0% unixbench.score 1996897 ± 0% -6.1% 1874635 ± 0% unixbench.time.involuntary_context_switches 1.721e+08 ± 0% -6.2% 1.613e+08 ± 0% unixbench.time.minor_page_faults 758.65 ± 0% -3.0% 735.86 ± 0% unixbench.time.system_time 387.66 ± 0% +5.4% 408.49 ± 0% unixbench.time.user_time 5950278 ± 0% -6.2% 5583456 ± 0% unixbench.time.voluntary_context_switches 1960642 ± 0% -11.4% 1737753 ± 0% cpuidle.C1-HSW.usage 5851 ± 0% -43.8% 3286 ± 1% proc-vmstat.nr_active_file 46185 ± 0% -21.2% 36385 ± 2% meminfo.Active 23404 ± 0% -43.8% 13147 ± 1% meminfo.Active(file) 4109 ± 5% -19.6% 3302 ± 4% slabinfo.pid.active_objs 4109 ± 5% -19.6% 3302 ± 4% slabinfo.pid.num_objs 94603 ± 0% -5.7% 89247 ± 0% vmstat.system.cs 8976 ± 0% -2.5% 8754 ± 0% vmstat.system.in 3.38 ± 2% +11.8% 3.77 ± 0% turbostat.CPU%c3 0.24 ±101% -86.3% 0.03 ± 54% turbostat.Pkg%pc3 66.53 ± 0% -1.7% 65.41 ± 0% turbostat.PkgWatt 2061 ± 1% -8.5% 1886 ± 0% sched_debug.cfs_rq:/.exec_clock.stddev 737154 ± 5% +10.8% 817107 ± 3% sched_debug.cpu.avg_idle.max 133057 ± 5% -33.2% 88864 ± 11% sched_debug.cpu.avg_idle.min 181562 ± 8% +15.9% 210434 ± 3% sched_debug.cpu.avg_idle.stddev 0.97 ± 7% +19.0% 1.16 ± 8% sched_debug.cpu.clock.stddev 0.97 ± 7% +19.0% 1.16 ± 8% sched_debug.cpu.clock_task.stddev 248.06 ± 11% +31.0% 324.94 ± 8% sched_debug.cpu.cpu_load[1].max 55.65 ± 14% +28.1% 71.30 ± 8% sched_debug.cpu.cpu_load[1].stddev 233.38 ± 10% +34.4% 313.56 ± 8% sched_debug.cpu.cpu_load[2].max 49.79 ± 15% +35.6% 67.50 ± 9% sched_debug.cpu.cpu_load[2].stddev 233.25 ± 12% +29.9% 302.94 ± 6% sched_debug.cpu.cpu_load[3].max 46.56 ± 8% +12.2% 52.25 ± 6% sched_debug.cpu.cpu_load[3].min 48.51 ± 15% +31.4% 63.76 ± 7% sched_debug.cpu.cpu_load[3].stddev 238.44 ± 12% +19.0% 283.69 ± 3% sched_debug.cpu.cpu_load[4].max 49.56 ± 9% +13.4% 56.19 ± 4% sched_debug.cpu.cpu_load[4].min 48.22 ± 13% +20.1% 57.93 ± 5% sched_debug.cpu.cpu_load[4].stddev 14792 ± 30% +71.9% 25424 ± 17% sched_debug.cpu.curr->pid.avg 42862 ± 1% +42.6% 61121 ± 0% sched_debug.cpu.curr->pid.max 19466 ± 10% +35.4% 26351 ± 9% sched_debug.cpu.curr->pid.stddev 1067 ± 6% -14.9% 909.35 ± 4% sched_debug.cpu.ttwu_local.stddev To reproduce: git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git cd lkp-tests bin/lkp install job.yaml # job file is attached in this email bin/lkp run job.yaml Disclaimer: Results have been estimated based on internal Intel analysis and are provided for informational purposes only. Any difference in system hardware or software design or configuration may affect actual performance. Thanks, Xiaolong

4 years, 8 months

8
22
0 / 0

[x86 tsc] b27ec289fd: WARNING: CPU: 0 PID: 0 at arch/x86/mm/extable.c:50 ex_handler_rdmsr_unsafe+0x48/0x99

by kernel test robot

FYI, we noticed the following commit: https://git.kernel.org/pub/scm/linux/kernel/git/lenb/linux.git release commit b27ec289fd005833b27d694d9c2dbb716c5cdff7 ("x86 tsc: enumerate SKL cpu_khz and tsc_khz via CPUID") on test machine: vm-kbuild-yocto-x86_64: 1 threads qemu-system-x86_64 -enable-kvm -cpu SandyBridge with 320M memory caused below changes: +-----------------------------------------------------------+------------+------------+ | | a6b5c3ecd3 | b27ec289fd | +-----------------------------------------------------------+------------+------------+ | boot_successes | 8 | 0 | | boot_failures | 2 | 10 | | IP-Config:Auto-configuration_of_network_failed | 2 | 4 | | WARNING:at_arch/x86/mm/extable.c:#ex_handler_rdmsr_unsafe | 0 | 6 | | backtrace:native_calibrate_cpu | 0 | 6 | | backtrace:tsc_init | 0 | 6 | | backtrace:x86_late_time_init | 0 | 6 | +-----------------------------------------------------------+------------+------------+ [ 0.000000] hpet clockevent registered [ 0.000000] ------------[ cut here ]------------ [ 0.000000] ------------[ cut here ]------------ [ 0.000000] WARNING: CPU: 0 PID: 0 at arch/x86/mm/extable.c:50 ex_handler_rdmsr_unsafe+0x48/0x99 [ 0.000000] WARNING: CPU: 0 PID: 0 at arch/x86/mm/extable.c:50 ex_handler_rdmsr_unsafe+0x48/0x99 [ 0.000000] unchecked MSR access error: RDMSR from 0xce [ 0.000000] unchecked MSR access error: RDMSR from 0xce [ 0.000000] CPU: 0 PID: 0 Comm: swapper Not tainted 4.7.0-rc3-00009-gb27ec28 #1 [ 0.000000] CPU: 0 PID: 0 Comm: swapper Not tainted 4.7.0-rc3-00009-gb27ec28 #1 [ 0.000000] 0000000000000000 [ 0.000000] 0000000000000000 ffffffff8f207930 ffffffff8f207930 ffffffff8c510068 ffffffff8c510068 ffffffff8f207970 ffffffff8f207970 [ 0.000000] ffffffff8bef8b3d [ 0.000000] ffffffff8bef8b3d 0000003200000009 0000003200000009 fffffbfff1e40f30 fffffbfff1e40f30 ffffffff8f207ac8 ffffffff8f207ac8 [ 0.000000] ffffffff8deb5a80 [ 0.000000] ffffffff8deb5a80 ffffffff8deb5a80 ffffffff8deb5a80 00000000ffffffff 00000000ffffffff ffffffff8f207a20 ffffffff8f207a20 [ 0.000000] Call Trace: [ 0.000000] Call Trace: [ 0.000000] [<ffffffff8c510068>] dump_stack+0x19/0x1b [ 0.000000] [<ffffffff8c510068>] dump_stack+0x19/0x1b [ 0.000000] [<ffffffff8bef8b3d>] __warn+0xee/0x109 [ 0.000000] [<ffffffff8bef8b3d>] __warn+0xee/0x109 [ 0.000000] [<ffffffff8bef8bed>] warn_slowpath_fmt+0x95/0xae [ 0.000000] [<ffffffff8bef8bed>] warn_slowpath_fmt+0x95/0xae [ 0.000000] [<ffffffff8bef8b58>] ? __warn+0x109/0x109 [ 0.000000] [<ffffffff8bef8b58>] ? __warn+0x109/0x109 [ 0.000000] [<ffffffff8de7a0ff>] ? retint_kernel+0x2c/0x2c [ 0.000000] [<ffffffff8de7a0ff>] ? retint_kernel+0x2c/0x2c [ 0.000000] [<ffffffff8be6204d>] ex_handler_rdmsr_unsafe+0x48/0x99 [ 0.000000] [<ffffffff8be6204d>] ex_handler_rdmsr_unsafe+0x48/0x99 [ 0.000000] [<ffffffff8be62005>] ? ex_handler_ext+0x60/0x60 [ 0.000000] [<ffffffff8be62005>] ? ex_handler_ext+0x60/0x60 [ 0.000000] [<ffffffff8be62221>] fixup_exception+0x54/0x60 [ 0.000000] [<ffffffff8be62221>] fixup_exception+0x54/0x60 [ 0.000000] [<ffffffff8be25705>] do_general_protection+0x85/0x20e [ 0.000000] [<ffffffff8be25705>] do_general_protection+0x85/0x20e [ 0.000000] [<ffffffff8de7b098>] general_protection+0x28/0x30 [ 0.000000] [<ffffffff8de7b098>] general_protection+0x28/0x30 [ 0.000000] [<ffffffff8be584af>] ? native_read_msr+0x8/0x38 [ 0.000000] [<ffffffff8be584af>] ? native_read_msr+0x8/0x38 [ 0.000000] [<ffffffff8be2e54f>] ? cpu_khz_from_msr+0xd9/0x1c2 [ 0.000000] [<ffffffff8be2e54f>] ? cpu_khz_from_msr+0xd9/0x1c2 [ 0.000000] [<ffffffff8be2e567>] cpu_khz_from_msr+0xf1/0x1c2 [ 0.000000] [<ffffffff8be2e567>] cpu_khz_from_msr+0xf1/0x1c2 [ 0.000000] [<ffffffff8be2dbf5>] native_calibrate_cpu+0x169/0x762 [ 0.000000] [<ffffffff8be2dbf5>] native_calibrate_cpu+0x169/0x762 [ 0.000000] [<ffffffff8c516955>] ? __radix_tree_lookup+0x10b/0x180 [ 0.000000] [<ffffffff8c516955>] ? __radix_tree_lookup+0x10b/0x180 [ 0.000000] [<ffffffff8c51684a>] ? __radix_tree_insert+0x16f/0x16f [ 0.000000] [<ffffffff8c51684a>] ? __radix_tree_insert+0x16f/0x16f [ 0.000000] [<ffffffff8be2da8c>] ? native_calibrate_tsc+0x1a4/0x1a4 [ 0.000000] [<ffffffff8be2da8c>] ? native_calibrate_tsc+0x1a4/0x1a4 [ 0.000000] [<ffffffff8c51d8a7>] ? format_decode+0x9b/0x387 [ 0.000000] [<ffffffff8c51d8a7>] ? format_decode+0x9b/0x387 [ 0.000000] [<ffffffff8c516a55>] ? radix_tree_lookup+0xd/0xf [ 0.000000] [<ffffffff8c516a55>] ? radix_tree_lookup+0xd/0xf [ 0.000000] [<ffffffff8bf445ec>] ? irq_to_desc+0x17/0x19 [ 0.000000] [<ffffffff8bf445ec>] ? irq_to_desc+0x17/0x19 [ 0.000000] [<ffffffff8bf4d886>] ? register_handler_proc+0x96/0x230 [ 0.000000] [<ffffffff8bf4d886>] ? register_handler_proc+0x96/0x230 [ 0.000000] [<ffffffff8be0201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 0.000000] [<ffffffff8be0201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 0.000000] [<ffffffff8bf4d7f0>] ? irq_spurious_proc_show+0x8e/0x8e [ 0.000000] [<ffffffff8bf4d7f0>] ? irq_spurious_proc_show+0x8e/0x8e [ 0.000000] [<ffffffff8de7a0ff>] ? retint_kernel+0x2c/0x2c [ 0.000000] [<ffffffff8de7a0ff>] ? retint_kernel+0x2c/0x2c [ 0.000000] [<ffffffff8bf4da20>] ? register_handler_proc+0x230/0x230 [ 0.000000] [<ffffffff8bf4da20>] ? register_handler_proc+0x230/0x230 [ 0.000000] [<ffffffff8de791c8>] ? _raw_spin_unlock_irqrestore+0x52/0x84 [ 0.000000] [<ffffffff8de791c8>] ? _raw_spin_unlock_irqrestore+0x52/0x84 [ 0.000000] [<ffffffff8fec1668>] tsc_init+0xac/0x5c6 [ 0.000000] [<ffffffff8fec1668>] tsc_init+0xac/0x5c6 [ 0.000000] [<ffffffff8fec15bc>] ? init_tsc_clocksource+0xe4/0xe4 [ 0.000000] [<ffffffff8fec15bc>] ? init_tsc_clocksource+0xe4/0xe4 [ 0.000000] [<ffffffff8bf46db6>] ? __setup_irq+0x697/0x829 [ 0.000000] [<ffffffff8bf46db6>] ? __setup_irq+0x697/0x829 [ 0.000000] [<ffffffff8bf470ab>] ? setup_irq+0xa1/0xbe [ 0.000000] [<ffffffff8bf470ab>] ? setup_irq+0xa1/0xbe [ 0.000000] [<ffffffff8febb906>] x86_late_time_init+0x1b/0x1d [ 0.000000] [<ffffffff8febb906>] x86_late_time_init+0x1b/0x1d [ 0.000000] [<ffffffff8febb906>] ? x86_late_time_init+0x1b/0x1d [ 0.000000] [<ffffffff8febb906>] ? x86_late_time_init+0x1b/0x1d [ 0.000000] [<ffffffff8feb12dd>] start_kernel+0x499/0x535 [ 0.000000] [<ffffffff8feb12dd>] start_kernel+0x499/0x535 [ 0.000000] [<ffffffff8feb0e44>] ? thread_info_cache_init+0x6/0x6 [ 0.000000] [<ffffffff8feb0e44>] ? thread_info_cache_init+0x6/0x6 [ 0.000000] [<ffffffff8de71bb3>] ? memblock_reserve+0x4a/0x4f [ 0.000000] [<ffffffff8de71bb3>] ? memblock_reserve+0x4a/0x4f [ 0.000000] [<ffffffff8feb0120>] ? early_idt_handler_array+0x120/0x120 [ 0.000000] [<ffffffff8feb0120>] ? early_idt_handler_array+0x120/0x120 [ 0.000000] [<ffffffff8feb0120>] ? early_idt_handler_array+0x120/0x120 [ 0.000000] [<ffffffff8feb0120>] ? early_idt_handler_array+0x120/0x120 [ 0.000000] [<ffffffff8feb02ae>] x86_64_start_reservations+0x2f/0x31 [ 0.000000] [<ffffffff8feb02ae>] x86_64_start_reservations+0x2f/0x31 [ 0.000000] [<ffffffff8feb042e>] x86_64_start_kernel+0x17e/0x18d [ 0.000000] [<ffffffff8feb042e>] x86_64_start_kernel+0x17e/0x18d [ 0.000000] ---[ end trace d97b395864960a98 ]--- [ 0.000000] ---[ end trace d97b395864960a98 ]--- FYI, raw QEMU command line is: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -kernel /pkg/linux/x86_64-randconfig-s1-06180836/gcc-6/b27ec289fd005833b27d694d9c2dbb716c5cdff7/vmlinuz-4.7.0-rc3-00009-gb27ec28 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-x86_64-29/bisect_boot-1-yocto-minimal-x86_64.cgz-x86_64-randconfig-s1-06180836-b27ec289fd005833b27d694d9c2dbb716c5cdff7-20160619-33752-1s8x56t-0.yaml~ ARCH=x86_64 kconfig=x86_64-randconfig-s1-06180836 branch=linux-devel/devel-hourly-2016061804 commit=b27ec289fd005833b27d694d9c2dbb716c5cdff7 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s1-06180836/gcc-6/b27ec289fd005833b27d694d9c2dbb716c5cdff7/vmlinuz-4.7.0-rc3-00009-gb27ec28 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-x86_64/yocto-minimal-x86_64.cgz/x86_64-randconfig-s1-06180836/gcc-6/b27ec289fd005833b27d694d9c2dbb716c5cdff7/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-x86_64-29::dhcp drbd.minor_count=8' -initrd /fs/sde1/initrd-vm-kbuild-yocto-x86_64-29 -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-x86_64-29,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-x86_64-29 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-x86_64-29 -daemonize -display none -monitor null Thanks, Kernel Test Robot

4 years, 8 months

1
0
0 / 0

[linux] ee941ea7e4: BUG: sleeping function called from invalid context at mm/slab.h:391

by kernel test robot

FYI, we noticed the following commit: git://git.cmpxchg.org/linux-mmotm.git master commit ee941ea7e45c890f672e020be4bf80dd197d51da ("linux-next") on test machine: vm-lkp-wsx03-2G: 2 threads qemu-system-x86_64 -enable-kvm -cpu host with 2G memory caused below changes: +----------------------------------------------------------------+------------+------------+ | | 0772b5fb1d | ee941ea7e4 | +----------------------------------------------------------------+------------+------------+ | boot_successes | 8 | 4 | | boot_failures | 0 | 4 | | BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 4 | | BUG:scheduling_while_atomic | 0 | 4 | | WARNING:at_kernel/time/timer.c:#del_timer_sync | 0 | 4 | | Kernel_panic-not_syncing:Aiee,killing_interrupt_handler | 0 | 3 | | backtrace:do_sys_open | 0 | 4 | | backtrace:SyS_open | 0 | 4 | | backtrace:SYSC_newstat | 0 | 4 | | backtrace:SyS_newstat | 0 | 4 | | backtrace:SYSC_newlstat | 0 | 4 | | backtrace:SyS_newlstat | 0 | 4 | +----------------------------------------------------------------+------------+------------+ NFS: Registering the id_resolver key type Key type id_resolver registered Key type id_legacy registered BUG: sleeping function called from invalid context at mm/slab.h:391 in_atomic(): 1, irqs_disabled(): 0, pid: 397, name: wrapper CPU: 1 PID: 397 Comm: wrapper Not tainted 4.7.0-rc3-mm1-00203-gee941ea #1 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88007ee57ac8 ffffffff8143843a ffff88007f3c0000 0000000000000187 ffff88007ee57ae0 ffffffff810a4b18 ffffffff81cb7fe0 ffff88007ee57b08 ffffffff810a4ba9 0000000002400040 0000000002400040 Call Trace: [<ffffffff8143843a>] dump_stack+0x63/0x89 [<ffffffff810a4b18>] ___might_sleep+0xd8/0x120 [<ffffffff810a4ba9>] __might_sleep+0x49/0x80 [<ffffffff811dea97>] kmem_cache_alloc_trace+0x167/0x1d0 [<ffffffff812ee41b>] ? nfs_check_verifier+0x6b/0x80 [<ffffffff812f43ef>] nfs_alloc_fattr+0x1f/0x70 [<ffffffff812f6c6d>] __nfs_revalidate_inode+0x4d/0x320 [<ffffffff812f23da>] nfs_do_access+0x2aa/0x350 [<ffffffff818e85ba>] ? generic_lookup_cred+0x1a/0x20 [<ffffffff818e6a6c>] ? rpcauth_lookupcred+0x7c/0xb0 [<ffffffff812f2532>] nfs_permission+0x82/0x1f0 [<ffffffff8120e45b>] __inode_permission+0x6b/0xc0 [<ffffffff8120e4c8>] inode_permission+0x18/0x50 [<ffffffff8120e8e2>] link_path_walk+0x72/0x590 [<ffffffff8120e6fd>] ? path_init+0x1fd/0x370 [<ffffffff8120ff5f>] path_openat+0xef/0x1560 [<ffffffff8117a592>] ? unlock_page+0x72/0x80 [<ffffffff8117b607>] ? filemap_map_pages+0x307/0x330 [<ffffffff811aef43>] ? handle_pte_fault+0x6c3/0x1040 [<ffffffff8121319e>] do_filp_open+0x7e/0xe0 [<ffffffff811de8e4>] ? kmem_cache_alloc+0x174/0x1c0 [<ffffffff81221aa8>] ? __alloc_fd+0xc8/0x190 [<ffffffff81201186>] do_sys_open+0x116/0x1f0 [<ffffffff8120127e>] SyS_open+0x1e/0x20 [<ffffffff8191daf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4 BUG: scheduling while atomic: wrapper/397/0x00000003 Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sr_mod cdrom ata_generic pata_acpi ppdev crc32c_intel ata_piix snd_pcm snd_timer i2c_piix4 parport_pc libata snd floppy soundcore pcspkr serio_raw parport acpi_cpufreq CPU: 1 PID: 397 Comm: wrapper Not tainted 4.7.0-rc3-mm1-00203-gee941ea #1 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88007ee577e0 ffffffff8143843a ffff880077318440 0000000000018440 ffff88007ee577f0 ffffffff810a452d ffff88007ee57848 ffffffff81918e9b ffff88007ee57810 0000000000000286 ffff88007f3c0000 Call Trace: [<ffffffff8143843a>] dump_stack+0x63/0x89 [<ffffffff810a452d>] __schedule_bug+0x4d/0x60 [<ffffffff81918e9b>] __schedule+0x50b/0x7a0 [<ffffffff810ece31>] ? mod_timer+0x111/0x210 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff8191916c>] schedule+0x3c/0x90 [<ffffffff818e2fe4>] rpc_wait_bit_killable+0x24/0xb0 [<ffffffff819195f1>] __wait_on_bit+0x61/0x90 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff81919692>] out_of_line_wait_on_bit+0x72/0x80 [<ffffffff810c2bd0>] ? autoremove_wake_function+0x40/0x40 [<ffffffff818d8510>] ? call_decode+0x870/0x870 [<ffffffff818e3e09>] __rpc_execute+0x159/0x470 [<ffffffff818e6398>] rpc_execute+0x68/0xd0 [<ffffffff818da359>] rpc_run_task+0x109/0x150 [<ffffffffa01251d6>] nfs4_call_sync_sequence+0x56/0x80 [nfsv4] [<ffffffffa0125ede>] _nfs4_proc_getattr+0xbe/0xd0 [nfsv4] [<ffffffffa0130da5>] nfs4_proc_getattr+0x55/0xe0 [nfsv4] [<ffffffff812f6cdd>] __nfs_revalidate_inode+0xbd/0x320 [<ffffffff812f23da>] nfs_do_access+0x2aa/0x350 [<ffffffff818e85ba>] ? generic_lookup_cred+0x1a/0x20 [<ffffffff818e6a6c>] ? rpcauth_lookupcred+0x7c/0xb0 [<ffffffff812f2532>] nfs_permission+0x82/0x1f0 [<ffffffff8120e45b>] __inode_permission+0x6b/0xc0 [<ffffffff8120e4c8>] inode_permission+0x18/0x50 [<ffffffff8120e8e2>] link_path_walk+0x72/0x590 [<ffffffff8120e6fd>] ? path_init+0x1fd/0x370 [<ffffffff8120ff5f>] path_openat+0xef/0x1560 [<ffffffff8117a592>] ? unlock_page+0x72/0x80 [<ffffffff8117b607>] ? filemap_map_pages+0x307/0x330 [<ffffffff811aef43>] ? handle_pte_fault+0x6c3/0x1040 [<ffffffff8121319e>] do_filp_open+0x7e/0xe0 [<ffffffff811de8e4>] ? kmem_cache_alloc+0x174/0x1c0 [<ffffffff81221aa8>] ? __alloc_fd+0xc8/0x190 [<ffffffff81201186>] do_sys_open+0x116/0x1f0 [<ffffffff8120127e>] SyS_open+0x1e/0x20 [<ffffffff8191daf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4 BUG: scheduling while atomic: wrapper/397/0x7fffffff Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sr_mod cdrom ata_generic pata_acpi ppdev crc32c_intel ata_piix snd_pcm snd_timer i2c_piix4 parport_pc libata snd floppy soundcore pcspkr serio_raw parport acpi_cpufreq CPU: 1 PID: 397 Comm: wrapper Tainted: G W 4.7.0-rc3-mm1-00203-gee941ea #1 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88007ee578b8 ffffffff8143843a ffff880077318440 0000000000018440 ffff88007ee578c8 ffffffff810a452d ffff88007ee57920 ffffffff81918e9b ffff880077317c40 0000000000000005 ffff88007f3c0000 Call Trace: [<ffffffff8143843a>] dump_stack+0x63/0x89 [<ffffffff810a452d>] __schedule_bug+0x4d/0x60 [<ffffffff81918e9b>] __schedule+0x50b/0x7a0 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff8191916c>] schedule+0x3c/0x90 [<ffffffff818e2fe4>] rpc_wait_bit_killable+0x24/0xb0 [<ffffffff819195f1>] __wait_on_bit+0x61/0x90 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff81919692>] out_of_line_wait_on_bit+0x72/0x80 [<ffffffff810c2bd0>] ? autoremove_wake_function+0x40/0x40 [<ffffffff818e2fbd>] __rpc_wait_for_completion_task+0x2d/0x30 [<ffffffffa0128472>] nfs4_run_open_task+0x122/0x180 [nfsv4] [<ffffffffa0130e8c>] _nfs4_open_and_get_state+0x5c/0x360 [nfsv4] [<ffffffffa0143284>] ? nfs_alloc_seqid+0x24/0x60 [nfsv4] [<ffffffffa012c76d>] ? nfs4_opendata_alloc+0x36d/0x4f0 [nfsv4] [<ffffffffa0131446>] nfs4_do_open+0x1b6/0x590 [nfsv4] [<ffffffffa01318f2>] nfs4_atomic_open+0xd2/0xf0 [nfsv4] [<ffffffff812ef43f>] nfs_atomic_open+0x14f/0x3b0 [<ffffffff81210b39>] path_openat+0xcc9/0x1560 [<ffffffff811781b6>] ? jump_label_module_notify+0xa6/0x250 [<ffffffff8121319e>] do_filp_open+0x7e/0xe0 [<ffffffff811de8e4>] ? kmem_cache_alloc+0x174/0x1c0 [<ffffffff81221aa8>] ? __alloc_fd+0xc8/0x190 [<ffffffff81201186>] do_sys_open+0x116/0x1f0 [<ffffffff8120127e>] SyS_open+0x1e/0x20 [<ffffffff8191daf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4 BUG: scheduling while atomic: wrapper/401/0x00000003 Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sr_mod cdrom ata_generic pata_acpi ppdev crc32c_intel ata_piix snd_pcm snd_timer i2c_piix4 parport_pc libata snd floppy soundcore pcspkr serio_raw parport acpi_cpufreq CPU: 1 PID: 401 Comm: wrapper Tainted: G W 4.7.0-rc3-mm1-00203-gee941ea #1 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88007ee2f7e0 ffffffff8143843a ffff880077318440 0000000000018440 ffff88007ee2f7f0 ffffffff810a452d ffff88007ee2f848 ffffffff81918e9b ffff88007ee2f810 0000000000000286 ffff88007f3c2480 Call Trace: [<ffffffff8143843a>] dump_stack+0x63/0x89 [<ffffffff810a452d>] __schedule_bug+0x4d/0x60 [<ffffffff81918e9b>] __schedule+0x50b/0x7a0 [<ffffffff810ece31>] ? mod_timer+0x111/0x210 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff8191916c>] schedule+0x3c/0x90 [<ffffffff818e2fe4>] rpc_wait_bit_killable+0x24/0xb0 [<ffffffff819195f1>] __wait_on_bit+0x61/0x90 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff81919692>] out_of_line_wait_on_bit+0x72/0x80 [<ffffffff810c2bd0>] ? autoremove_wake_function+0x40/0x40 [<ffffffff818d8510>] ? call_decode+0x870/0x870 [<ffffffff818e3e09>] __rpc_execute+0x159/0x470 [<ffffffff818e6398>] rpc_execute+0x68/0xd0 [<ffffffff818da359>] rpc_run_task+0x109/0x150 [<ffffffffa01251d6>] nfs4_call_sync_sequence+0x56/0x80 [nfsv4] [<ffffffffa0125ede>] _nfs4_proc_getattr+0xbe/0xd0 [nfsv4] [<ffffffff8145567d>] ? list_del+0xd/0x30 [<ffffffffa0130da5>] nfs4_proc_getattr+0x55/0xe0 [nfsv4] [<ffffffff812f6cdd>] __nfs_revalidate_inode+0xbd/0x320 [<ffffffff812f23da>] nfs_do_access+0x2aa/0x350 [<ffffffff818e85ba>] ? generic_lookup_cred+0x1a/0x20 [<ffffffff818e6a6c>] ? rpcauth_lookupcred+0x7c/0xb0 [<ffffffff812f2532>] nfs_permission+0x82/0x1f0 [<ffffffff8120e45b>] __inode_permission+0x6b/0xc0 [<ffffffff8120e4c8>] inode_permission+0x18/0x50 [<ffffffff8120e8e2>] link_path_walk+0x72/0x590 [<ffffffff8120e6fd>] ? path_init+0x1fd/0x370 [<ffffffff8120ff5f>] path_openat+0xef/0x1560 [<ffffffff8117a592>] ? unlock_page+0x72/0x80 [<ffffffff8117b607>] ? filemap_map_pages+0x307/0x330 [<ffffffff811aef43>] ? handle_pte_fault+0x6c3/0x1040 [<ffffffff8121319e>] do_filp_open+0x7e/0xe0 [<ffffffff811de8e4>] ? kmem_cache_alloc+0x174/0x1c0 [<ffffffff81221aa8>] ? __alloc_fd+0xc8/0x190 [<ffffffff81201186>] do_sys_open+0x116/0x1f0 [<ffffffff8120127e>] SyS_open+0x1e/0x20 [<ffffffff8191daf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4 BUG: scheduling while atomic: wrapper/401/0x7fffffff Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sr_mod cdrom ata_generic pata_acpi ppdev crc32c_intel ata_piix snd_pcm snd_timer i2c_piix4 parport_pc libata snd floppy soundcore pcspkr serio_raw parport acpi_cpufreq CPU: 1 PID: 401 Comm: wrapper Tainted: G W 4.7.0-rc3-mm1-00203-gee941ea #1 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88007ee2f8b8 ffffffff8143843a ffff880077318440 0000000000018440 ffff88007ee2f8c8 ffffffff810a452d ffff88007ee2f920 ffffffff81918e9b ffff880077317c40 0000000000000005 ffff88007f3c2480 Call Trace: [<ffffffff8143843a>] dump_stack+0x63/0x89 [<ffffffff810a452d>] __schedule_bug+0x4d/0x60 [<ffffffff81918e9b>] __schedule+0x50b/0x7a0 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff8191916c>] schedule+0x3c/0x90 [<ffffffff818e2fe4>] rpc_wait_bit_killable+0x24/0xb0 [<ffffffff819195f1>] __wait_on_bit+0x61/0x90 [<ffffffff818e2fc0>] ? __rpc_wait_for_completion_task+0x30/0x30 [<ffffffff81919692>] out_of_line_wait_on_bit+0x72/0x80 [<ffffffff810c2bd0>] ? autoremove_wake_function+0x40/0x40 [<ffffffff818e2fbd>] __rpc_wait_for_completion_task+0x2d/0x30 [<ffffffffa0128472>] nfs4_run_open_task+0x122/0x180 [nfsv4] [<ffffffffa0130e8c>] _nfs4_open_and_get_state+0x5c/0x360 [nfsv4] [<ffffffffa0143284>] ? nfs_alloc_seqid+0x24/0x60 [nfsv4] [<ffffffffa012c76d>] ? nfs4_opendata_alloc+0x36d/0x4f0 [nfsv4] [<ffffffffa0131446>] nfs4_do_open+0x1b6/0x590 [nfsv4] [<ffffffffa01318f2>] nfs4_atomic_open+0xd2/0xf0 [nfsv4] [<ffffffff812ef43f>] nfs_atomic_open+0x14f/0x3b0 [<ffffffff81210b39>] path_openat+0xcc9/0x1560 [<ffffffff811781b6>] ? jump_label_module_notify+0xa6/0x250 [<ffffffff8121319e>] do_filp_open+0x7e/0xe0 [<ffffffff811de8e4>] ? kmem_cache_alloc+0x174/0x1c0 [<ffffffff81221aa8>] ? __alloc_fd+0xc8/0x190 [<ffffffff81201186>] do_sys_open+0x116/0x1f0 [<ffffffff8120127e>] SyS_open+0x1e/0x20 [<ffffffff8191daf2>] entry_SYSCALL_64_fastpath+0x1a/0xa4 BUG: sleeping function called from invalid context at mm/slab.h:391 in_atomic(): 1, irqs_disabled(): 0, pid: 326, name: run-lkp CPU: 1 PID: 326 Comm: run-lkp Tainted: G W 4.7.0-rc3-mm1-00203-gee941ea #1 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88007ecfbae0 ffffffff8143843a ffff88007f142480 0000000000000187 ffff88007ecfbaf8 ffffffff810a4b18 ffffffff81cb7fe0 FYI, raw QEMU command line is: qemu-system-x86_64 -enable-kvm -cpu host -kernel /pkg/linux/x86_64-rhel/gcc-4.9/ee941ea7e45c890f672e020be4bf80dd197d51da/vmlinuz-4.7.0-rc3-mm1-00203-gee941ea -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-2G-2/bisect_boot-1-debian-x86_64-2015-02-07.cgz-x86_64-rhel-ee941ea7e45c890f672e020be4bf80dd197d51da-20160618-36414-1ja67ei-0.yaml~ ARCH=x86_64 kconfig=x86_64-rhel branch=yhuang/thp_find_target_node commit=ee941ea7e45c890f672e020be4bf80dd197d51da BOOT_IMAGE=/pkg/linux/x86_64-rhel/gcc-4.9/ee941ea7e45c890f672e020be4bf80dd197d51da/vmlinuz-4.7.0-rc3-mm1-00203-gee941ea max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-2G/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/ee941ea7e45c890f672e020be4bf80dd197d51da/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-2G-2::dhcp' -initrd /fs/sdc1/initrd-vm-lkp-wsx03-2G-2 -m 2048 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23621-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdc1/disk0-vm-lkp-wsx03-2G-2,media=disk,if=virtio -drive file=/fs/sdc1/disk1-vm-lkp-wsx03-2G-2,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-2G-2 -serial file:/dev/shm/kboot/serial-vm-lkp-wsx03-2G-2 -daemonize -display none -monitor null Thanks, Kernel Test Robot

4 years, 8 months

1
0
0 / 0

[drm] e28cd4d0a2: INFO: trying to register non-static key.

by kernel test robot

FYI, we noticed the following commit: git://anongit.freedesktop.org/drm-intel topic/drm-misc commit e28cd4d0a223e1bcea616326e2281900e7e7e9a2 ("drm: Automatically register/unregister all connectors") on test machine: vm-lkp-wsx03-yocto-i386: 1 threads qemu-system-i386 -enable-kvm with 320M memory caused below changes: +------------------------------------------+------------+------------+ | | acd8f414c9 | e28cd4d0a2 | +------------------------------------------+------------+------------+ | boot_successes | 71 | 0 | | boot_failures | 0 | 8 | | INFO:trying_to_register_non-static_key | 0 | 8 | | BUG:unable_to_handle_kernel | 0 | 8 | | Oops:#[##] | 0 | 8 | | EIP_is_at_mutex_lock_nested | 0 | 8 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 8 | | backtrace:drm_dev_register | 0 | 8 | | backtrace:vgem_init | 0 | 8 | | backtrace:kernel_init_freeable | 0 | 8 | +------------------------------------------+------------+------------+ [ 1.338384] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 1.340531] toshiba: not a supported Toshiba laptop [ 1.341126] [drm] Initialized drm 1.1.0 20060810 [ 1.342029] INFO: trying to register non-static key. [ 1.342535] the code is fine but needs lockdep annotation. [ 1.343074] turning off the locking correctness validator. [ 1.343610] CPU: 0 PID: 1 Comm: swapper Not tainted 4.7.0-rc2-00564-ge28cd4d #1 [ 1.344330] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 1.345192] 00000000 00000000 d28dbdc8 c12a197b d28dbe04 c1058c18 c16986b2 c169870c [ 1.346063] c16986e2 c14e3fdc 00000000 00000000 d28dbdf0 c1027c67 00000000 00000000 [ 1.346917] d39e8b24 00000001 00000000 d28dbe74 c105b796 c14e3fdc c1af7ee4 c1af7ee4 [ 1.347772] Call Trace: [ 1.348031] [<c12a197b>] dump_stack+0x16/0x18 [ 1.348450] [<c1058c18>] register_lock_class+0x164/0x3d1 [ 1.348985] [<c1027c67>] ? kvm_sched_clock_read+0x9/0x18 [ 1.349509] [<c105b796>] __lock_acquire+0x95/0x13c4 [ 1.350007] [<c105c7d3>] ? __lock_acquire+0x10d2/0x13c4 [ 1.350530] [<c10588a5>] ? __lock_is_held+0x24/0x43 [ 1.351029] [<c105cdac>] lock_acquire+0x59/0x77 [ 1.351477] [<c1361cf9>] ? drm_connector_register_all+0x1a/0x92 [ 1.352053] [<c14db8db>] mutex_lock_nested+0x4d/0x2c3 [ 1.352554] [<c1361cf9>] ? drm_connector_register_all+0x1a/0x92 [ 1.353140] [<c1361cf9>] ? drm_connector_register_all+0x1a/0x92 [ 1.353720] [<c105b4bf>] ? trace_hardirqs_on+0xb/0xd [ 1.354239] [<c1361cf9>] drm_connector_register_all+0x1a/0x92 [ 1.354845] [<c10b25be>] ? kstrdup+0x25/0x3a [ 1.355271] [<c135e37d>] drm_dev_register+0x59/0x99 [ 1.355781] [<c195ea3e>] vgem_init+0x34/0x49 [ 1.356213] [<c195ea0a>] ? mipi_dsi_bus_init+0xf/0xf [ 1.356702] [<c100048f>] do_one_initcall+0x7c/0xfd [ 1.357182] [<c104b409>] ? parse_args+0x1fd/0x314 [ 1.357648] [<c1939c10>] ? kernel_init_freeable+0xd0/0x179 [ 1.358203] [<c1939c2c>] kernel_init_freeable+0xec/0x179 [ 1.358763] [<c14d66ea>] kernel_init+0x8/0xcb [ 1.359222] [<c14debce>] ret_from_kernel_thread+0xe/0x30 [ 1.359744] [<c14d66e2>] ? rest_init+0x10e/0x10e [ 1.360244] BUG: unable to handle kernel NULL pointer dereference at (null) [ 1.360972] IP: [<c14db9ad>] mutex_lock_nested+0x11f/0x2c3 [ 1.361512] *pde = 00000000 [ 1.361827] Oops: 0002 [#1] [ 1.362123] Modules linked in: [ 1.362451] CPU: 0 PID: 1 Comm: swapper Not tainted 4.7.0-rc2-00564-ge28cd4d #1 [ 1.363202] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 1.364105] task: c03d0000 ti: d28da000 task.ti: d28da000 [ 1.364636] EIP: 0060:[<c14db9ad>] EFLAGS: 00210096 CPU: 0 [ 1.365215] EIP is at mutex_lock_nested+0x11f/0x2c3 [ 1.365703] EAX: 00000000 EBX: d39e8ae8 ECX: d39e8b14 EDX: c1361cf9 [ 1.366351] ESI: c03d0000 EDI: d28dbed0 EBP: d28dbeec ESP: d28dbec0 [ 1.367010] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 [ 1.367534] CR0: 80050033 CR2: 00000000 CR3: 019a9000 CR4: 00000690 [ 1.368152] Stack: [ 1.368356] d39e8b14 d39e8b24 c1361cf9 00200246 d39e8b14 00000000 11111111 d28dbed0 [ 1.369235] d39e8800 d39e8ae8 00000000 d28dbf08 c1361cf9 d28dbf0c c10b25be d39e8800 [ 1.370087] 00000000 00000000 d28dbf1c c135e37d fffffff4 ffffffff 00000000 d28dbf28 [ 1.371012] Call Trace: [ 1.371272] [<c1361cf9>] ? drm_connector_register_all+0x1a/0x92 [ 1.371847] [<c1361cf9>] drm_connector_register_all+0x1a/0x92 [ 1.372421] [<c10b25be>] ? kstrdup+0x25/0x3a [ 1.372863] [<c135e37d>] drm_dev_register+0x59/0x99 [ 1.373358] [<c195ea3e>] vgem_init+0x34/0x49 [ 1.373770] [<c195ea0a>] ? mipi_dsi_bus_init+0xf/0xf [ 1.374257] [<c100048f>] do_one_initcall+0x7c/0xfd [ 1.374754] [<c104b409>] ? parse_args+0x1fd/0x314 [ 1.375259] [<c1939c10>] ? kernel_init_freeable+0xd0/0x179 [ 1.375837] [<c1939c2c>] kernel_init_freeable+0xec/0x179 [ 1.376371] [<c14d66ea>] kernel_init+0x8/0xcb [ 1.376806] [<c14debce>] ret_from_kernel_thread+0xe/0x30 [ 1.377322] [<c14d66e2>] ? rest_init+0x10e/0x10e [ 1.377754] Code: 89 fa e8 71 c5 b7 ff 8b 4e 04 89 fa 89 d8 e8 8e c6 b7 ff 8d 43 2c 89 45 d4 8b 43 30 8d 4b 2c 89 45 e8 89 7b 30 89 4d e4 8b 55 dc <89> 38 8d 43 3c 89 75 ec e8 c9 dd b7 ff eb 0c 31 c0 87 03 48 75 [ 1.380442] EIP: [<c14db9ad>] mutex_lock_nested+0x11f/0x2c3 SS:ESP 0068:d28dbec0 [ 1.381174] CR2: 0000000000000000 [ 1.381503] ---[ end trace e260f2ca50706006 ]--- [ 1.381966] Kernel panic - not syncing: Fatal exception FYI, raw QEMU command line is: qemu-system-i386 -enable-kvm -kernel /pkg/linux/i386-randconfig-b0-06172203/gcc-6/e28cd4d0a223e1bcea616326e2281900e7e7e9a2/vmlinuz-4.7.0-rc2-00564-ge28cd4d -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-yocto-i386-2/bisect_boot-1-yocto-minimal-i386.cgz-i386-randconfig-b0-06172203-e28cd4d0a223e1bcea616326e2281900e7e7e9a2-20160618-131023-156vzgq-0.yaml~ ARCH=i386 kconfig=i386-randconfig-b0-06172203 branch=linux-devel/devel-spot-201606172115 commit=e28cd4d0a223e1bcea616326e2281900e7e7e9a2 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-06172203/gcc-6/e28cd4d0a223e1bcea616326e2281900e7e7e9a2/vmlinuz-4.7.0-rc2-00564-ge28cd4d max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-yocto-i386/yocto-minimal-i386.cgz/i386-randconfig-b0-06172203/gcc-6/e28cd4d0a223e1bcea616326e2281900e7e7e9a2/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-yocto-i386-2::dhcp drbd.minor_count=8' -initrd /fs/sdc1/initrd-vm-lkp-wsx03-yocto-i386-2 -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/sdc1/disk0-vm-lkp-wsx03-yocto-i386-2,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-yocto-i386-2 -serial file:/dev/shm/kboot/serial-vm-lkp-wsx03-yocto-i386-2 -daemonize -display none -monitor null Thanks, Kernel Test Robot

4 years, 8 months

3
3
0 / 0

[svcrpc] 12e5cf2d75: BUG: unable to handle kernel NULL pointer dereference at 0000000000000014

by kernel test robot

FYI, we noticed the following commit: https://github.com/0day-ci/linux Chuck-Lever/NFS-RDMA-client-patches-proposed-for-v4-8/20160615-114209 commit 12e5cf2d7547fc43a3004cdf52e562abfc95eb55 ("svcrpc: remove unnecessary svc_exit_thread serv check") in testcase: fsmark with following parameters: disk=1HDD/filesize=16MB/fs2=nfsv4/fs=ext4/iterations=1x/nr_directories=16d/nr_files_per_directory=256fpd/nr_threads=32t/sync_method=NoSync/test_size=60G on test machine: vm-vp-1G: 2 threads qemu-system-x86_64 -enable-kvm -cpu Nehalem with 1G memory caused below changes: +------------------------------------------+------------+------------+ | | 5a9570253a | 12e5cf2d75 | +------------------------------------------+------------+------------+ | boot_successes | 8 | 4 | | boot_failures | 0 | 4 | | BUG:unable_to_handle_kernel | 0 | 4 | | Oops | 0 | 4 | | RIP:svc_destroy | 0 | 4 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 4 | | backtrace:nfsd | 0 | 4 | +------------------------------------------+------------+------------+ [ 106.491736] random: nonblocking pool is initialized Terminated [ 3635.898268] LKP: rebooting [ 3636.338752] BUG: unable to handle kernel NULL pointer dereference at 0000000000000014 [ 3636.340257] IP: [<ffffffff81a25435>] svc_destroy+0xa/0x8e [ 3636.341284] PGD 0 [ 3636.341715] Oops: 0000 [#1] SMP [ 3636.342301] Modules linked in: acpi_cpufreq [ 3636.343143] CPU: 0 PID: 2686 Comm: nfsd Not tainted 4.7.0-rc3-00006-g12e5cf2 #1 [ 3636.344447] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 3636.351717] task: ffff88003e8f2580 ti: ffff88003e6ac000 task.ti: ffff88003e6ac000 [ 3636.353040] RIP: 0010:[<ffffffff81a25435>] [<ffffffff81a25435>] svc_destroy+0xa/0x8e [ 3636.354525] RSP: 0000:ffff88003e6afe68 EFLAGS: 00010246 [ 3636.355434] RAX: 0000000000002710 RBX: ffff880019714000 RCX: 0000000000000001 [ 3636.356660] RDX: ffffffff8225df80 RSI: 0000000000000010 RDI: 0000000000000000 [ 3636.357864] RBP: ffff88003e6afe70 R08: 0000000000000001 R09: ffffffff81a25511 [ 3636.359725] R10: ffff88003e6afe38 R11: 0000000000000124 R12: ffff88003ed2f500 [ 3636.361563] R13: ffff88003ed2f504 R14: 0000000000000000 R15: 0000000000000000 [ 3636.363402] FS: 0000000000000000(0000) GS:ffff880038200000(0000) knlGS:0000000000000000 [ 3636.366025] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 3636.367651] CR2: 0000000000000014 CR3: 0000000035a55000 CR4: 00000000000006f0 [ 3636.369493] Stack: [ 3636.370482] ffff880019714000 ffff88003e6afea0 ffffffff81a25769 ffff880019714000 [ 3636.372930] ffffffff82355c00 ffff88001c674c00 ffff88003e8f2580 ffff88003e6afec8 [ 3636.375241] ffffffff8137b367 ffff8800227a1040 ffff880019714000 ffffffff8137b228 [ 3636.377532] Call Trace: [ 3636.378458] [<ffffffff81a25769>] svc_exit_thread+0x72/0x7b [ 3636.392474] [<ffffffff8137b367>] nfsd+0x13f/0x15c [ 3636.393743] [<ffffffff8137b228>] ? nfsd_destroy+0x6a/0x6a [ 3636.395098] [<ffffffff8111d605>] kthread+0xdd/0xe5 [ 3636.396387] [<ffffffff81a9bc5f>] ret_from_fork+0x1f/0x40 [ 3636.397725] [<ffffffff8111d528>] ? kthread_create_on_node+0x18a/0x18a [ 3636.399234] Code: e8 58 e0 00 00 48 8b 83 98 00 00 00 48 8b 00 48 85 c0 74 08 4c 89 e6 48 89 df ff d0 5b 41 5c 5d c3 66 66 66 66 90 55 48 89 e5 53 <8b> 47 14 48 89 fb 85 c0 74 10 ff c8 85 c0 89 47 14 74 18 e8 ba [ 3636.418176] RIP [<ffffffff81a25435>] svc_destroy+0xa/0x8e [ 3636.419584] RSP <ffff88003e6afe68> [ 3636.420643] CR2: 0000000000000014 [ 3636.422097] ---[ end trace c906da9fce8f6a66 ]--- [ 3636.423409] Kernel panic - not syncing: Fatal exception FYI, raw QEMU command line is: qemu-system-x86_64 -enable-kvm -cpu Nehalem -kernel /pkg/linux/x86_64-lkp/gcc-4.9/12e5cf2d7547fc43a3004cdf52e562abfc95eb55/vmlinuz-4.7.0-rc3-00006-g12e5cf2 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-1G-6/bisect_fsmark-1x-32t-1HDD-ext4-nfsv4-16MB-60G-NoSync-16d-256fpd-debian-x86_64-2015-02-07.cgz-x86_64-lkp-12e5cf2d7547fc43a3004cdf52e562abfc95eb55-20160616-124433-16zojbo-0.yaml~ ARCH=x86_64 kconfig=x86_64-lkp branch=linux-devel/devel-spot-201606151525 commit=12e5cf2d7547fc43a3004cdf52e562abfc95eb55 BOOT_IMAGE=/pkg/linux/x86_64-lkp/gcc-4.9/12e5cf2d7547fc43a3004cdf52e562abfc95eb55/vmlinuz-4.7.0-rc3-00006-g12e5cf2 max_uptime=3600 RESULT_ROOT=/result/fsmark/1x-32t-1HDD-ext4-nfsv4-16MB-60G-NoSync-16d-256fpd/vm-vp-1G/debian-x86_64-2015-02-07.cgz/x86_64-lkp/gcc-4.9/12e5cf2d7547fc43a3004cdf52e562abfc95eb55/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-vp-1G-6::dhcp' -initrd /fs/sdh1/initrd-vm-vp-1G-6 -m 1024 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23105-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdh1/disk0-vm-vp-1G-6,media=disk,if=virtio -drive file=/fs/sdh1/disk1-vm-vp-1G-6,media=disk,if=virtio -drive file=/fs/sdh1/disk2-vm-vp-1G-6,media=disk,if=virtio -drive file=/fs/sdh1/disk3-vm-vp-1G-6,media=disk,if=virtio -drive file=/fs/sdh1/disk4-vm-vp-1G-6,media=disk,if=virtio -drive file=/fs/sdh1/disk5-vm-vp-1G-6,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-vp-1G-6 -serial file:/dev/shm/kboot/serial-vm-vp-1G-6 -daemonize -display none -monitor null 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 Thanks, Xiaolong

4 years, 8 months

2
1
0 / 0

[linux] 1e38bcb055: INFO: suspicious RCU usage. ]

by kernel test robot

FYI, we noticed the following commit: https://git.kernel.org/pub/scm/linux/kernel/git/mel/linux mm-vmscan-node-lru-v7r15 commit 1e38bcb0550fa03da7035446eecb1bc07ef8c8c8 ("linux-next") on test machine: vm-kbuild-1G: 2 threads qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap with 1G memory caused below changes: +----------------------------------------------------------------+------------+------------+ | | d313dc67d1 | 1e38bcb055 | +----------------------------------------------------------------+------------+------------+ | boot_successes | 17 | 2 | | boot_failures | 0 | 11 | | INFO:suspicious_RCU_usage | 0 | 11 | | BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 11 | | BUG:scheduling_while_atomic | 0 | 11 | | WARNING:at_kernel/softirq.c:#__local_bh_disable_ip | 0 | 11 | | WARNING:at_kernel/time/timer.c:#del_timer_sync | 0 | 11 | | Kernel_panic-not_syncing:Aiee,killing_interrupt_handler | 0 | 8 | | backtrace:do_sys_open | 0 | 11 | | backtrace:SyS_open | 0 | 11 | | backtrace:SYSC_newstat | 0 | 11 | | backtrace:SyS_newstat | 0 | 11 | | backtrace:SYSC_newlstat | 0 | 10 | | backtrace:SyS_newlstat | 0 | 10 | +----------------------------------------------------------------+------------+------------+ mount.nfs (1100) used greatest stack depth: 11440 bytes left =============================== [ INFO: suspicious RCU usage. ] 4.7.0-rc3-mm1-00203-g1e38bcb #338 Not tainted ------------------------------- include/linux/rcupdate.h:556 Illegal context switch in RCU read-side critical section! other info that might help us debug this: rcu_scheduler_active = 1, debug_locks = 0 2 locks held by wrapper/1152: #0: (rcu_read_lock){......}, at: [<ffffffff81228bc8>] rcu_read_lock+0x0/0x68 #1: (rcu_read_lock){......}, at: [<ffffffff8135be1b>] rcu_read_lock+0x0/0x68 stack backtrace: CPU: 1 PID: 1152 Comm: wrapper Not tainted 4.7.0-rc3-mm1-00203-g1e38bcb #338 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88005bdafa98 ffffffff8177b8d1 ffff88005c8f0ec0 0000000000000001 ffff88005bdafac8 ffffffff81121540 ffffffff839ff9dd 0000000000000187 0000000000000000 0000000002400040 ffff88005bdafad8 Call Trace: [<ffffffff8177b8d1>] dump_stack+0x82/0xb8 [<ffffffff81121540>] lockdep_rcu_suspicious+0xf7/0x100 [<ffffffff81106d42>] rcu_preempt_sleep_check+0x45/0x47 [<ffffffff81106d60>] ___might_sleep+0x1c/0x1c4 [<ffffffff81106f7d>] __might_sleep+0x75/0x7c [<ffffffff812077e5>] slab_pre_alloc_hook+0x32/0x3f [<ffffffff81209569>] kmem_cache_alloc_trace+0x3d/0x133 [<ffffffff81361a40>] ? nfs_alloc_fattr+0x20/0x37 [<ffffffff81361a40>] nfs_alloc_fattr+0x20/0x37 [<ffffffff81363517>] __nfs_revalidate_inode+0xeb/0x2eb [<ffffffff8135edd7>] nfs_do_access+0x3ec/0x46f [<ffffffff82d41059>] ? generic_lookup_cred+0x1a/0x1c [<ffffffff82d3ffa8>] ? rpcauth_lookupcred+0x61/0x6a [<ffffffff8135ef51>] nfs_permission+0xbe/0x188 [<ffffffff81226b4e>] __inode_permission+0x3e/0xa0 [<ffffffff81226bef>] inode_permission+0x3f/0x41 [<ffffffff812292a8>] link_path_walk+0x47/0x478 [<ffffffff812297f6>] ? path_init+0x11d/0x2a4 [<ffffffff81229f12>] path_openat+0x2ba/0x750 [<ffffffff8109bfd8>] ? kvm_clock_read+0x25/0x2e [<ffffffff8122ba0e>] do_filp_open+0x4d/0xa3 [<ffffffff8110df59>] ? sched_clock_cpu+0x15/0xae [<ffffffff82eda4bd>] ? _raw_spin_unlock+0x27/0x31 [<ffffffff812375fb>] ? __alloc_fd+0x1b2/0x1c4 [<ffffffff8121c55c>] do_sys_open+0x72/0x102 [<ffffffff8121c55c>] ? do_sys_open+0x72/0x102 [<ffffffff8121c60a>] SyS_open+0x1e/0x20 [<ffffffff82edad7c>] entry_SYSCALL_64_fastpath+0x1f/0xbd [<ffffffff8111f86f>] ? trace_hardirqs_off_caller+0x3f/0xab BUG: sleeping function called from invalid context at mm/slab.h:391 in_atomic(): 1, irqs_disabled(): 0, pid: 1152, name: wrapper INFO: lockdep is turned off. CPU: 1 PID: 1152 Comm: wrapper Not tainted 4.7.0-rc3-mm1-00203-g1e38bcb #338 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88005bdafad8 ffffffff8177b8d1 ffff88005c8f0ec0 0000000000000480 ffff88005bdafb00 ffffffff81106f01 ffffffff839ff9dd 0000000000000187 0000000000000000 ffff88005bdafb28 ffffffff81106f7d Call Trace: [<ffffffff8177b8d1>] dump_stack+0x82/0xb8 [<ffffffff81106f01>] ___might_sleep+0x1bd/0x1c4 [<ffffffff81106f7d>] __might_sleep+0x75/0x7c [<ffffffff812077e5>] slab_pre_alloc_hook+0x32/0x3f [<ffffffff81209569>] kmem_cache_alloc_trace+0x3d/0x133 [<ffffffff81361a40>] ? nfs_alloc_fattr+0x20/0x37 [<ffffffff81361a40>] nfs_alloc_fattr+0x20/0x37 [<ffffffff81363517>] __nfs_revalidate_inode+0xeb/0x2eb [<ffffffff8135edd7>] nfs_do_access+0x3ec/0x46f [<ffffffff82d41059>] ? generic_lookup_cred+0x1a/0x1c [<ffffffff82d3ffa8>] ? rpcauth_lookupcred+0x61/0x6a [<ffffffff8135ef51>] nfs_permission+0xbe/0x188 [<ffffffff81226b4e>] __inode_permission+0x3e/0xa0 [<ffffffff81226bef>] inode_permission+0x3f/0x41 [<ffffffff812292a8>] link_path_walk+0x47/0x478 [<ffffffff812297f6>] ? path_init+0x11d/0x2a4 [<ffffffff81229f12>] path_openat+0x2ba/0x750 [<ffffffff8109bfd8>] ? kvm_clock_read+0x25/0x2e [<ffffffff8122ba0e>] do_filp_open+0x4d/0xa3 [<ffffffff8110df59>] ? sched_clock_cpu+0x15/0xae [<ffffffff82eda4bd>] ? _raw_spin_unlock+0x27/0x31 [<ffffffff812375fb>] ? __alloc_fd+0x1b2/0x1c4 [<ffffffff8121c55c>] do_sys_open+0x72/0x102 [<ffffffff8121c55c>] ? do_sys_open+0x72/0x102 [<ffffffff8121c60a>] SyS_open+0x1e/0x20 [<ffffffff82edad7c>] entry_SYSCALL_64_fastpath+0x1f/0xbd [<ffffffff8111f86f>] ? trace_hardirqs_off_caller+0x3f/0xab BUG: scheduling while atomic: wrapper/1152/0x00000003 INFO: lockdep is turned off. Modules linked in: CPU: 1 PID: 1152 Comm: wrapper Not tainted 4.7.0-rc3-mm1-00203-g1e38bcb #338 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88005bdaf7d8 ffffffff8177b8d1 ffff88005c8f0ec0 0000000000000000 ffff88005bdaf7f0 ffffffff81106466 ffff8800569d9040 ffff88005bdaf850 ffffffff82ed6095 ffff88005bdaf820 00ffffff811229ba Call Trace: [<ffffffff8177b8d1>] dump_stack+0x82/0xb8 [<ffffffff81106466>] __schedule_bug+0x61/0x70 [<ffffffff82ed6095>] __schedule+0x6b/0x76f [<ffffffff82d3e160>] ? trace_event_raw_event_xs_tcp_data_ready+0xd4/0xd4 [<ffffffff82ed6821>] schedule+0x88/0x9d [<ffffffff82d3e184>] rpc_wait_bit_killable+0x24/0xab [<ffffffff82ed6d60>] __wait_on_bit+0x4d/0x7b [<ffffffff82ed6e00>] out_of_line_wait_on_bit+0x72/0x7d [<ffffffff82d3e160>] ? trace_event_raw_event_xs_tcp_data_ready+0xd4/0xd4 [<ffffffff81119687>] ? autoremove_wake_function+0x3a/0x3a [<ffffffff82d359a3>] ? call_transmit_status+0xa4/0xa4 [<ffffffff82d3f222>] __rpc_execute+0x19b/0x411 [<ffffffff811194cd>] ? wake_up_bit+0x25/0x2a [<ffffffff82d3f753>] rpc_execute+0x105/0x16d [<ffffffff82d360f4>] rpc_run_task+0x121/0x12d [<ffffffff81376b67>] nfs4_call_sync_sequence+0x55/0x73 [<ffffffff8137e0b3>] nfs4_call_sync+0x31/0x33 [<ffffffff81380aba>] _nfs4_proc_getattr+0xb6/0xc8 [<ffffffff8177b902>] ? dump_stack+0xb3/0xb8 [<ffffffff81380b15>] nfs4_proc_getattr+0x49/0x172 [<ffffffff81380b15>] ? nfs4_proc_getattr+0x49/0x172 [<ffffffff81361a40>] ? nfs_alloc_fattr+0x20/0x37 [<ffffffff81363553>] __nfs_revalidate_inode+0x127/0x2eb [<ffffffff8135edd7>] nfs_do_access+0x3ec/0x46f [<ffffffff82d41059>] ? generic_lookup_cred+0x1a/0x1c [<ffffffff82d3ffa8>] ? rpcauth_lookupcred+0x61/0x6a [<ffffffff8135ef51>] nfs_permission+0xbe/0x188 [<ffffffff81226b4e>] __inode_permission+0x3e/0xa0 [<ffffffff81226bef>] inode_permission+0x3f/0x41 [<ffffffff812292a8>] link_path_walk+0x47/0x478 [<ffffffff812297f6>] ? path_init+0x11d/0x2a4 [<ffffffff81229f12>] path_openat+0x2ba/0x750 [<ffffffff8109bfd8>] ? kvm_clock_read+0x25/0x2e [<ffffffff8122ba0e>] do_filp_open+0x4d/0xa3 [<ffffffff8110df59>] ? sched_clock_cpu+0x15/0xae [<ffffffff82eda4bd>] ? _raw_spin_unlock+0x27/0x31 [<ffffffff812375fb>] ? __alloc_fd+0x1b2/0x1c4 [<ffffffff8121c55c>] do_sys_open+0x72/0x102 [<ffffffff8121c55c>] ? do_sys_open+0x72/0x102 [<ffffffff8121c60a>] SyS_open+0x1e/0x20 [<ffffffff82edad7c>] entry_SYSCALL_64_fastpath+0x1f/0xbd [<ffffffff8111f86f>] ? trace_hardirqs_off_caller+0x3f/0xab BUG: scheduling while atomic: wrapper/1152/0x7fffffff INFO: lockdep is turned off. Modules linked in: CPU: 1 PID: 1152 Comm: wrapper Tainted: G W 4.7.0-rc3-mm1-00203-g1e38bcb #338 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88005bdaf8a8 ffffffff8177b8d1 ffff88005c8f0ec0 0000000000000000 ffff88005bdaf8c0 ffffffff81106466 ffff8800569d9040 ffff88005bdaf920 ffffffff82ed6095 ffff88005bdaf8f0 00ffffff811229ba Call Trace: [<ffffffff8177b8d1>] dump_stack+0x82/0xb8 [<ffffffff81106466>] __schedule_bug+0x61/0x70 [<ffffffff82ed6095>] __schedule+0x6b/0x76f [<ffffffff82d3e160>] ? trace_event_raw_event_xs_tcp_data_ready+0xd4/0xd4 [<ffffffff82ed6821>] schedule+0x88/0x9d [<ffffffff82d3e184>] rpc_wait_bit_killable+0x24/0xab [<ffffffff82ed6d60>] __wait_on_bit+0x4d/0x7b [<ffffffff82ed6e00>] out_of_line_wait_on_bit+0x72/0x7d [<ffffffff82d3e160>] ? trace_event_raw_event_xs_tcp_data_ready+0xd4/0xd4 [<ffffffff81119687>] ? autoremove_wake_function+0x3a/0x3a [<ffffffff82d3d762>] __rpc_wait_for_completion_task+0x2d/0x2f [<ffffffff81376e3c>] rpc_wait_for_completion_task+0xb/0xd [<ffffffff8137827f>] nfs4_run_open_task+0x108/0x135 [<ffffffff81381e46>] nfs4_do_open+0x1f5/0x6e7 [<ffffffff813823bc>] nfs4_atomic_open+0xe/0x1c [<ffffffff8135fd2c>] nfs_atomic_open+0x1ce/0x33c [<ffffffff81227508>] lookup_open+0x2b5/0x4fb [<ffffffff8111eea9>] ? update_fast_ctr+0x1e/0x3e [<ffffffff8111eea9>] ? update_fast_ctr+0x1e/0x3e [<ffffffff8122a014>] path_openat+0x3bc/0x750 [<ffffffff8109bfd8>] ? kvm_clock_read+0x25/0x2e [<ffffffff8122ba0e>] do_filp_open+0x4d/0xa3 [<ffffffff8110df59>] ? sched_clock_cpu+0x15/0xae [<ffffffff82eda4bd>] ? _raw_spin_unlock+0x27/0x31 [<ffffffff812375fb>] ? __alloc_fd+0x1b2/0x1c4 [<ffffffff8121c55c>] do_sys_open+0x72/0x102 [<ffffffff8121c55c>] ? do_sys_open+0x72/0x102 [<ffffffff8121c60a>] SyS_open+0x1e/0x20 [<ffffffff82edad7c>] entry_SYSCALL_64_fastpath+0x1f/0xbd [<ffffffff8111f86f>] ? trace_hardirqs_off_caller+0x3f/0xab BUG: scheduling while atomic: wrapper/1157/0x00000003 INFO: lockdep is turned off. Modules linked in: CPU: 1 PID: 1157 Comm: wrapper Tainted: G W 4.7.0-rc3-mm1-00203-g1e38bcb #338 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 0000000000000000 ffff88005bd477d8 ffffffff8177b8d1 ffff88005bd58e80 0000000000000000 ffff88005bd477f0 ffffffff81106466 ffff8800569d9040 ffff88005bd47850 ffffffff82ed6095 ffff88005bd47820 00ffffff811229ba Call Trace: [<ffffffff8177b8d1>] dump_stack+0x82/0xb8 [<ffffffff81106466>] __schedule_bug+0x61/0x70 [<ffffffff82ed6095>] __schedule+0x6b/0x76f [<ffffffff82d3e160>] ? trace_event_raw_event_xs_tcp_data_ready+0xd4/0xd4 [<ffffffff82ed6821>] schedule+0x88/0x9d [<ffffffff82d3e184>] rpc_wait_bit_killable+0x24/0xab [<ffffffff82ed6d60>] __wait_on_bit+0x4d/0x7b [<ffffffff82ed6e00>] out_of_line_wait_on_bit+0x72/0x7d [<ffffffff82d3e160>] ? trace_event_raw_event_xs_tcp_data_ready+0xd4/0xd4 [<ffffffff81119687>] ? autoremove_wake_function+0x3a/0x3a [<ffffffff82d359a3>] ? call_transmit_status+0xa4/0xa4 [<ffffffff82d3f222>] __rpc_execute+0x19b/0x411 [<ffffffff811194cd>] ? wake_up_bit+0x25/0x2a [<ffffffff82d3f753>] rpc_execute+0x105/0x16d FYI, raw QEMU command line is: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-allyesdebian/gcc-6/1e38bcb0550fa03da7035446eecb1bc07ef8c8c8/vmlinuz-4.7.0-rc3-mm1-00203-g1e38bcb -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-1G-12/bisect_boot-1-debian-x86_64-2015-02-07.cgz-x86_64-allyesdebian-1e38bcb0550fa03da7035446eecb1bc07ef8c8c8-20160618-48145-1qc134s-0.yaml~ ARCH=x86_64 kconfig=x86_64-allyesdebian branch=linux-devel/devel-catchup-201606172211 commit=1e38bcb0550fa03da7035446eecb1bc07ef8c8c8 BOOT_IMAGE=/pkg/linux/x86_64-allyesdebian/gcc-6/1e38bcb0550fa03da7035446eecb1bc07ef8c8c8/vmlinuz-4.7.0-rc3-mm1-00203-g1e38bcb max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-1G/debian-x86_64-2015-02-07.cgz/x86_64-allyesdebian/gcc-6/1e38bcb0550fa03da7035446eecb1bc07ef8c8c8/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-12::dhcp' -initrd /fs/sdg1/initrd-vm-kbuild-1G-12 -m 1536 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23011-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -device virtio-scsi-pci,id=scsi0 -drive file=/fs/sdg1/disk0-vm-kbuild-1G-12,if=none,id=hd0,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd0,scsi-id=1,lun=0 -drive file=/fs/sdg1/disk1-vm-kbuild-1G-12,if=none,id=hd1,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd1,scsi-id=1,lun=1 -drive file=/fs/sdg1/disk2-vm-kbuild-1G-12,if=none,id=hd2,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd2,scsi-id=1,lun=2 -drive file=/fs/sdg1/disk3-vm-kbuild-1G-12,if=none,id=hd3,media=disk,aio=native,cache=none -device scsi-hd,bus=scsi0.0,drive=hd3,scsi-id=1,lun=3 -drive file=/fs/sdg1/disk4-vm-kbuild-1G-12,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-12 -serial file:/dev/shm/kboot/serial-vm-kbuild-1G-12 -daemonize -display none -monitor null Thanks, Kernel Test Robot

4 years, 8 months

1
0
0 / 0

[lkp] [thermal] 24bc2fa031: BUG: KASAN: slab-out-of-bounds in thermal_zone_device_register+0x749/0x10a0 at addr ffff880000134140

by kernel test robot

FYI, we noticed the following commit: https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master commit 24bc2fa031f094c05c017a862732924cb46ad2c1 ("thermal: core: move trips attributes to tz->device.groups") on test machine: vm-lkp-wsx03-quantal-x86_64: 2 threads qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap with 360M memory caused below changes: +----------------------------------------------------------------------+------------+------------+ | | 79f34853ba | 24bc2fa031 | +----------------------------------------------------------------------+------------+------------+ | boot_successes | 2 | 0 | | boot_failures | 6 | 8 | | IP-Config:Auto-configuration_of_network_failed | 6 | 4 | | BUG:KASAN:slab-out-of-bounds_in_thermal_zone_device_register_at_addr | 0 | 8 | | BUG:KASAN:slab-out-of-bounds_in_internal_create_group_at_addr | 0 | 8 | | backtrace:power_supply_register | 0 | 8 | | backtrace:test_power_init | 0 | 8 | | backtrace:kernel_init_freeable | 0 | 8 | +----------------------------------------------------------------------+------------+------------+ [ 7.103767] __power_supply_register: Expected proper parent device for 'test_ac' [ 7.105699] __power_supply_register: Expected proper parent device for 'test_battery' [ 7.108318] ================================================================== [ 7.109325] BUG: KASAN: slab-out-of-bounds in thermal_zone_device_register+0x749/0x10a0 at addr ffff880000134140 [ 7.110709] Write of size 8 by task swapper/1 [ 7.111312] CPU: 0 PID: 1 Comm: swapper Not tainted 4.7.0-rc3-00014-g24bc2fa0 #1 [ 7.112309] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 7.113506] ffff880000134160 ffff880010897b58 ffffffff817356d9 ffff880010897bd8 [ 7.114588] ffffffff811ebffc 0000000000000000 0000000000000000 0000000000000297 [ 7.115652] ffffffff811eadf5 ffff88000013415f 1ffff1000002682c ffff880010897bd8 [ 7.116720] Call Trace: [ 7.117080] [<ffffffff817356d9>] dump_stack+0x19/0x20 [ 7.117777] [<ffffffff811ebffc>] kasan_report_error+0x1ec/0x5a0 [ 7.118598] [<ffffffff811eadf5>] ? kasan_unpoison_shadow+0x35/0x50 [ 7.119454] [<ffffffff811eb4a9>] ? kasan_kmalloc+0xd9/0x100 [ 7.120239] [<ffffffff811ec3e3>] kasan_report+0x33/0x40 [ 7.120967] [<ffffffff81d44e39>] ? thermal_zone_device_register+0x749/0x10a0 [ 7.121949] [<ffffffff811ebc81>] __asan_store8+0x61/0x70 [ 7.122699] [<ffffffff81d44e39>] thermal_zone_device_register+0x749/0x10a0 [ 7.123646] [<ffffffff81d446f0>] ? thermal_notify_framework+0x10/0x10 [ 7.124545] [<ffffffff818f0640>] ? dev_warn+0xe0/0xe0 [ 7.125259] [<ffffffff821c5d49>] ? _raw_spin_unlock_irq+0x9/0x20 [ 7.126104] [<ffffffff8190959c>] ? device_wakeup_enable+0xbc/0x110 [ 7.126956] [<ffffffff81ca1179>] __power_supply_register+0x5c9/0x700 [ 7.127840] [<ffffffff81ca185e>] power_supply_register+0xe/0x10 [ 7.128663] [<ffffffff83269716>] test_power_init+0x35/0xeb [ 7.129433] [<ffffffff832696e1>] ? wm8350_power_driver_init+0x14/0x14 [ 7.130328] [<ffffffff832222cd>] do_one_initcall+0xef/0x1a1 [ 7.131111] [<ffffffff83221aae>] ? repair_env_string+0x2f/0x73 [ 7.131912] [<ffffffff832221de>] ? start_kernel+0x447/0x447 [ 7.132689] [<ffffffff810fca00>] ? parameq+0xa0/0xa0 [ 7.133385] [<ffffffff83221a7f>] ? set_debug_rodata+0x12/0x12 [ 7.134192] [<ffffffff83222516>] kernel_init_freeable+0x197/0x228 [ 7.135042] [<ffffffff821b4d2e>] kernel_init+0xe/0x110 [ 7.135751] [<ffffffff821c629f>] ret_from_fork+0x1f/0x40 [ 7.136488] [<ffffffff821b4d20>] ? rest_init+0x90/0x90 [ 7.137207] Object at ffff880000134140, in cache kmalloc-32 [ 7.137952] Object allocated with size 1 bytes. [ 7.138572] Allocation: [ 7.138909] PID = 1 [ 7.139216] [<ffffffff81032e55>] save_stack_trace+0x25/0x40 [ 7.139993] [<ffffffff811ead36>] save_stack+0x46/0xd0 [ 7.140714] [<ffffffff811eb4a9>] kasan_kmalloc+0xd9/0x100 [ 7.141479] [<ffffffff811e92a6>] __kmalloc+0xe6/0x1d0 [ 7.142201] [<ffffffff81d44a9c>] thermal_zone_device_register+0x3ac/0x10a0 [ 7.143162] [<ffffffff81ca1179>] __power_supply_register+0x5c9/0x700 [ 7.144076] [<ffffffff81ca185e>] power_supply_register+0xe/0x10 [ 7.144913] [<ffffffff83269716>] test_power_init+0x35/0xeb [ 7.145698] [<ffffffff832222cd>] do_one_initcall+0xef/0x1a1 [ 7.146487] [<ffffffff83222516>] kernel_init_freeable+0x197/0x228 [ 7.147359] [<ffffffff821b4d2e>] kernel_init+0xe/0x110 [ 7.148105] [<ffffffff821c629f>] ret_from_fork+0x1f/0x40 [ 7.148860] Memory state around the buggy address: [ 7.149548] ffff880000134000: 00 fc fc fc fc fc fc fc 00 03 fc fc fc fc fc fc [ 7.150578] ffff880000134080: 06 fc fc fc fc fc fc fc 03 fc fc fc fc fc fc fc FYI, raw QEMU command line is: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-randconfig-s1-06151451/gcc-6/24bc2fa031f094c05c017a862732924cb46ad2c1/vmlinuz-4.7.0-rc3-00014-g24bc2fa0 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-quantal-x86_64-8/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-randconfig-s1-06151451-24bc2fa031f094c05c017a862732924cb46ad2c1-20160615-110339-ip9qk6-0.yaml~ ARCH=x86_64 kconfig=x86_64-randconfig-s1-06151451 branch=linux-devel/devel-spot-201606151338 commit=24bc2fa031f094c05c017a862732924cb46ad2c1 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s1-06151451/gcc-6/24bc2fa031f094c05c017a862732924cb46ad2c1/vmlinuz-4.7.0-rc3-00014-g24bc2fa0 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-s1-06151451/gcc-6/24bc2fa031f094c05c017a862732924cb46ad2c1/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-quantal-x86_64-8::dhcp drbd.minor_count=8' -initrd /fs/sdc1/initrd-vm-lkp-wsx03-quantal-x86_64-8 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-quantal-x86_64-8 -serial file:/dev/shm/kboot/serial-vm-lkp-wsx03-quantal-x86_64-8 -daemonize -display none -monitor null Thanks, Xiaolong

4 years, 8 months

2
1
0 / 0

[x86] 33c4f826f2: INFO: task swapper/0:1 blocked for more than 120 seconds.

by kernel test robot

FYI, we noticed the following commit: https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/vmap_stack commit 33c4f826f210da9ea4f198dea266f425f13615e9 ("x86: Move addr_limit to thread_struct") on test machine: vm-vp-quantal-x86_64: 2 threads qemu-system-x86_64 -enable-kvm with 360M memory caused below changes: +--------------------------------------------------+------------+------------+ | | 59927176f3 | 33c4f826f2 | +--------------------------------------------------+------------+------------+ | boot_successes | 22 | 0 | | boot_failures | 1 | 45 | | BUG:kernel_test_crashed | 1 | | | INFO:task_blocked_for_more_than#seconds | 0 | 29 | | RIP:__default_send_IPI_dest_field | 0 | 29 | | RIP:native_safe_halt | 0 | 17 | | Kernel_panic-not_syncing:hung_task:blocked_tasks | 0 | 29 | | backtrace:misc_register | 0 | 45 | | backtrace:vga_arb_device_init | 0 | 45 | | backtrace:kernel_init_freeable | 0 | 45 | | backtrace:watchdog | 0 | 29 | | backtrace:cpu_startup_entry | 0 | 5 | | general_protection_fault:#[##]SMP | 0 | 16 | | RIP:__lock_acquire | 0 | 16 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 16 | | RIP:lock_time_inc | 0 | 1 | +--------------------------------------------------+------------+------------+ [ 1.581053] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.583300] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 1.590883] ACPI: Enabled 16 GPEs in block 00 to 0F [ 244.074282] INFO: task swapper/0:1 blocked for more than 120 seconds. [ 244.082592] Not tainted 4.7.0-rc3-00015-g33c4f82 #353 [ 244.084853] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 244.088477] swapper/0 D ffff880013bf3af8 12600 1 0 0x00000000 [ 244.091658] ffff880013bf3af8 00ff880013be88a0 ffffffff00000001 ffff880013dd7500 [ 244.096323] ffff880013dd7518 ffff880013be8040 ffff880013bf4000 7fffffffffffffff [ 244.100521] ffff880013bf3c98 0000000000000002 ffff880013be8040 ffff880013bf3b10 [ 244.104673] Call Trace: [ 244.106316] [<ffffffff81cc79f5>] schedule+0x88/0x9d [ 244.108431] [<ffffffff81ccb462>] schedule_timeout+0x3f/0x31d [ 244.110827] [<ffffffff8108bcf5>] ? kvm_sched_clock_read+0x9/0x12 [ 244.113350] [<ffffffff8106ab51>] ? sched_clock+0x9/0xd [ 244.115521] [<ffffffff810ea679>] ? mark_held_locks+0x5e/0x74 [ 244.117775] [<ffffffff81ccc227>] ? _raw_spin_unlock_irq+0x2c/0x3d [ 244.120637] [<ffffffff810ea80d>] ? trace_hardirqs_on_caller+0x17e/0x19a [ 244.123573] [<ffffffff81cc833e>] wait_for_common+0x114/0x153 [ 244.126302] [<ffffffff81cc833e>] ? wait_for_common+0x114/0x153 [ 244.128606] [<ffffffff810ca6bd>] ? wake_up_q+0x56/0x56 [ 244.130880] [<ffffffff81cc839a>] wait_for_completion+0x1d/0x1f [ 244.133433] [<ffffffff817442b0>] devtmpfs_create_node+0x109/0x126 [ 244.136261] [<ffffffff81cc826e>] ? wait_for_common+0x44/0x153 [ 244.138847] [<ffffffff8173b538>] device_add+0x3d6/0x50f [ 244.141359] [<ffffffff811b4926>] ? kfree_const+0x20/0x22 [ 244.143578] [<ffffffff8173b805>] device_create_groups_vargs+0xb8/0xed [ 244.146024] [<ffffffff827b1831>] ? set_debug_rodata+0x12/0x12 [ 244.148413] [<ffffffff8173b8bd>] device_create_with_groups+0x30/0x32 [ 244.151425] [<ffffffff827b1831>] ? set_debug_rodata+0x12/0x12 [ 244.158945] [<ffffffff81650668>] misc_register+0xb8/0x121 [ 244.161127] [<ffffffff82802806>] ? trace_event_define_fields_i915_gem_object_pwrite+0x88/0x88 [ 244.164800] [<ffffffff82802820>] vga_arb_device_init+0x1a/0x1a7 [ 244.167026] [<ffffffff82802806>] ? trace_event_define_fields_i915_gem_object_pwrite+0x88/0x88 [ 244.169677] [<ffffffff827b1831>] ? set_debug_rodata+0x12/0x12 [ 244.171836] [<ffffffff8100042b>] do_one_initcall+0x90/0x123 [ 244.173729] [<ffffffff827b1831>] ? set_debug_rodata+0x12/0x12 [ 244.175680] [<ffffffff827b20d0>] kernel_init_freeable+0x1cf/0x257 [ 244.177344] [<ffffffff81cbea12>] kernel_init+0xe/0xf5 [ 244.178838] [<ffffffff81cccc2f>] ret_from_fork+0x1f/0x40 [ 244.180374] [<ffffffff81cbea04>] ? rest_init+0x13b/0x13b [ 244.181915] 1 lock held by swapper/0/1: [ 244.183210] #0: (misc_mtx){+.+.+.}, at: [<ffffffff816505de>] misc_register+0x2e/0x121 [ 244.186232] Sending NMI to all CPUs: [ 244.187630] NMI backtrace for cpu 0 [ 244.189322] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.7.0-rc3-00015-g33c4f82 #353 [ 244.192239] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 244.195870] task: ffffffff8241d540 ti: ffffffff82400000 task.ti: ffffffff82400000 [ 244.198984] RIP: 0010:[<ffffffff8108c10f>] [<ffffffff8108c10f>] native_safe_halt+0x6/0x8 [ 244.248149] RSP: 0000:ffffffff82403eb0 EFLAGS: 00000202 [ 244.249658] RAX: ffffffff8241d540 RBX: 0000000000000000 RCX: 0000000000000003 [ 244.251441] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff8241d540 [ 244.271341] RBP: ffffffff82403eb0 R08: 0000004df88fedb8 R09: 0000000000000001 [ 244.273148] R10: ffffffff82403e28 R11: ffffffff810c12ee R12: 0000000000000000 [ 244.274933] R13: 0000000000000000 R14: ffffffff82400000 R15: ffffffff82400000 [ 244.276722] FS: 0000000000000000(0000) GS:ffff880013c00000(0000) knlGS:0000000000000000 [ 244.279237] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 244.289843] CR2: 00000000ffffffff CR3: 0000000002418000 CR4: 00000000000006f0 [ 244.291638] Stack: [ 244.292631] ffffffff82403ed8 ffffffff8106b8f0 0000000000000000 ffffffff82400000 [ 244.295036] 0000000000000000 ffffffff82403ee8 ffffffff8106c168 ffffffff82403ef8 [ 244.297449] ffffffff810e0f6f ffffffff82403f38 ffffffff810e1188 ffffffff82404000 [ 244.317920] Call Trace: [ 244.318985] [<ffffffff8106b8f0>] default_idle+0x62/0x1b0 [ 244.320506] [<ffffffff8106c168>] arch_cpu_idle+0xf/0x11 [ 244.322013] [<ffffffff810e0f6f>] default_idle_call+0x3d/0x44 [ 244.323586] [<ffffffff810e1188>] cpu_startup_entry+0x212/0x3e2 [ 244.337259] [<ffffffff81cbe9fe>] rest_init+0x135/0x13b [ 244.338779] [<ffffffff827b1ef4>] start_kernel+0x404/0x411 [ 244.340306] [<ffffffff827b1120>] ? early_idt_handler_array+0x120/0x120 [ 244.342023] [<ffffffff827b129e>] x86_64_start_reservations+0x2f/0x31 [ 244.343695] [<ffffffff827b13de>] x86_64_start_kernel+0x13e/0x14d [ 244.345315] Code: 48 89 e5 0f 09 5d c3 55 48 89 e5 9c 58 5d c3 55 48 89 e5 57 9d 5d c3 55 48 89 e5 fa 5d c3 55 48 89 e5 fb 5d c3 55 48 89 e5 fb f4 <5d> c3 55 48 89 e5 f4 5d c3 55 49 89 c9 49 89 d0 8b 07 48 89 e5 [ 244.372091] NMI backtrace for cpu 1 [ 244.373272] CPU: 1 PID: 646 Comm: khungtaskd Not tainted 4.7.0-rc3-00015-g33c4f82 #353 [ 244.375660] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 244.378733] task: ffff8800132d8040 ti: ffff8800133f8000 task.ti: ffff8800133f8000 [ 244.381788] RIP: 0010:[<ffffffff8108147d>] [<ffffffff8108147d>] __default_send_IPI_dest_field+0x5d/0x63 [ 244.385251] RSP: 0000:ffff8800133fbd60 EFLAGS: 00000046 [ 244.387351] RAX: 0000000000000000 RBX: 0000000000000c00 RCX: ffff8800132d8040 [ 244.389969] RDX: 0000000000000c00 RSI: 0000000000000002 RDI: 0000000003000000 [ 244.392216] RBP: ffff8800133fbd78 R08: 0000000000000001 R09: 0000000000000000 [ 244.394055] R10: ffffffff830dc8e0 R11: 00000000000fffa0 R12: 0000000000000001 [ 244.395922] R13: 0000000000000002 R14: ffff880013be83d0 R15: 00000000000003ff [ 244.397787] FS: 0000000000000000(0000) GS:ffff880013e00000(0000) knlGS:0000000000000000 [ 244.400415] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 244.402039] CR2: 00000000ffffffff CR3: 0000000002418000 CR4: 00000000000006e0 [ 244.404226] Stack: [ 244.405642] 00000003132d8040 0000000000000286 ffffffff81082459 ffff8800133fbda0 [ 244.409074] ffffffff81084d98 0000000000000003 000000028224a083 0000000000000001 [ 244.412578] ffff8800133fbdb0 ffffffff81084e5c ffff8800133fbdc0 ffffffff81082474 [ 244.416026] Call Trace: [ 244.417498] [<ffffffff81082459>] ? irq_force_complete_move+0x100/0x100 [ 244.419459] [<ffffffff81084d98>] _flat_send_IPI_mask+0x41/0x6c [ 244.420992] [<ffffffff81084e5c>] flat_send_IPI_mask+0x11/0x13 [ 244.422534] [<ffffffff81082474>] nmi_raise_cpu_backtrace+0x1b/0x1d [ 244.424119] [<ffffffff8155e77c>] nmi_trigger_all_cpu_backtrace+0xa6/0xe2 [ 244.425882] [<ffffffff810824a5>] arch_trigger_all_cpu_backtrace+0x19/0x1b [ 244.428337] [<ffffffff81155709>] watchdog+0x4aa/0x58d [ 244.430337] [<ffffffff8115525f>] ? reset_hung_task_detector+0x15/0x15 [ 244.432707] [<ffffffff810c0033>] kthread+0xfb/0x103 [ 244.435934] [<ffffffff81cccc2f>] ret_from_fork+0x1f/0x40 [ 244.441229] [<ffffffff810bff38>] ? kthread_create_on_node+0x1ca/0x1ca [ 244.443418] Code: ff 0f ba e0 0c 73 04 f3 90 eb ef c1 e7 18 89 3c 25 10 d3 5f ff 44 89 ea 09 da 80 cf 04 41 83 fd 02 0f 44 d3 89 14 25 00 d3 5f ff <58> 5b 41 5d 5d c3 0f 1f 44 00 00 55 48 89 e5 41 54 4c 63 e7 53 [ 244.449990] Kernel panic - not syncing: hung_task: blocked tasks [ 244.452088] CPU: 1 PID: 646 Comm: khungtaskd Not tainted 4.7.0-rc3-00015-g33c4f82 #353 [ 244.454867] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 244.457602] 0000000000000000 ffff8800133fbd78 ffffffff8155a9ad ffffffff8220c086 [ 244.460631] ffff8800133fbe08 ffff8800133fbdf8 ffffffff81193ace ffff880000000008 [ 244.464064] ffff8800133fbe08 ffff8800133fbda0 ffffffff810fd7f8 0000000000000000 [ 244.466985] Call Trace: [ 244.468105] [<ffffffff8155a9ad>] dump_stack+0x86/0xc0 [ 244.469549] [<ffffffff81193ace>] panic+0xd7/0x21e [ 244.471889] [<ffffffff810fd7f8>] ? printk_nmi_flush+0x32/0x88 [ 244.473450] [<ffffffff81155715>] watchdog+0x4b6/0x58d [ 244.474941] [<ffffffff8115525f>] ? reset_hung_task_detector+0x15/0x15 [ 244.477126] [<ffffffff810c0033>] kthread+0xfb/0x103 [ 244.479144] [<ffffffff81cccc2f>] ret_from_fork+0x1f/0x40 [ 244.481339] [<ffffffff810bff38>] ? kthread_create_on_node+0x1ca/0x1ca Elapsed time: 250 qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-nfsroot/gcc-6/33c4f826f210da9ea4f198dea266f425f13615e9/vmlinuz-4.7.0-rc3-00015-g33c4f82 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-35/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-nfsroot-33c4f826f210da9ea4f198dea266f425f13615e9-20160617-28771-184jeog-1.yaml~ ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-devel/devel-catchup-201606170800 commit=33c4f826f210da9ea4f198dea266f425f13615e9 BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-6/33c4f826f210da9ea4f198dea266f425f13615e9/vmlinuz-4.7.0-rc3-00015-g33c4f82 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-nfsroot/gcc-6/33c4f826f210da9ea4f198dea266f425f13615e9/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-vp-quantal-x86_64-35::dhcp drbd.minor_count=8' -initrd /fs/sdf1/initrd-vm-vp-quantal-x86_64-35 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-vp-quantal-x86_64-35 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-35 -daemonize -display none -monitor null FYI, raw QEMU command line is: qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-nfsroot/gcc-6/33c4f826f210da9ea4f198dea266f425f13615e9/vmlinuz-4.7.0-rc3-00015-g33c4f82 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-35/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-nfsroot-33c4f826f210da9ea4f198dea266f425f13615e9-20160617-28771-184jeog-1.yaml~ ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-devel/devel-catchup-201606170800 commit=33c4f826f210da9ea4f198dea266f425f13615e9 BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-6/33c4f826f210da9ea4f198dea266f425f13615e9/vmlinuz-4.7.0-rc3-00015-g33c4f82 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-nfsroot/gcc-6/33c4f826f210da9ea4f198dea266f425f13615e9/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-vp-quantal-x86_64-35::dhcp drbd.minor_count=8' -initrd /fs/sdf1/initrd-vm-vp-quantal-x86_64-35 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-vp-quantal-x86_64-35 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-35 -daemonize -display none -monitor null Thanks, Kernel Test Robot

4 years, 8 months

1
0
0 / 0

[x86] 5ac0c41bf3: WARNING: CPU: 0 PID: 0 at arch/x86/mm/extable.c:50 ex_handler_rdmsr_unsafe

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is https://github.com/0day-ci/linux Borislav-Petkov/x86-Move-away-from-dev-cpu-msr/20160615-180354 commit 5ac0c41bf307ad74185284004062d85a21ecb82b Author: Borislav Petkov <bp(a)alien8.de> AuthorDate: Wed Jun 15 12:00:29 2016 +0200 Commit: 0day robot <fengguang.wu(a)intel.com> CommitDate: Wed Jun 15 18:04:00 2016 +0800 x86: Move away from /dev/cpu/*/msr Hi people, so we've been talking about this for a long time now - how loading msr.ko is not a good thing and how userspace shouldn't poke at random MSRs. So my intention is to move away users in tools/ which did write to MSRs through the char dev and replace it with proper sysfs et al interfaces. Once that's done, we can start tainting the kernel when writing to MSRs from that device or even forbid it completely at some point. We'll see. Anyway, here's a first attempt, please scream if something's not right. Functionality-wise, it should be equivalent as I'm exporting the pref_hint of the IA32_ENERGY_PERF_BIAS in sysfs and it lands under /sys/devices/system/cpu/cpu?/energy_policy_pref_hint where anything with sufficient perms can read/write it. Comments are, as always, appreciated. +-----------------------------------------------------------+------------+------------+------------+ | | 0a26309f44 | 5ac0c41bf3 | d2cf7f5a55 | +-----------------------------------------------------------+------------+------------+------------+ | boot_successes | 62 | 0 | 0 | | boot_failures | 3 | 26 | 13 | | BUG:unable_to_handle_kernel | 2 | 1 | | | Oops | 3 | 1 | | | EIP_is_at_perf_prepare_sample | 3 | 1 | | | Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 1 | | | | backtrace:iterate_dir | 2 | | | | backtrace:SyS_getdents64 | 2 | | | | Kernel_panic-not_syncing:Fatal_exception | 2 | 1 | | | WARNING:at_arch/x86/mm/extable.c:#ex_handler_rdmsr_unsafe | 0 | 26 | 13 | | WARNING:at_arch/x86/mm/extable.c:#ex_handler_wrmsr_unsafe | 0 | 26 | 13 | | backtrace:error_code | 0 | 26 | 13 | | backtrace:init_intel_energy_perf | 0 | 26 | 13 | | backtrace:init_intel | 0 | 26 | 13 | +-----------------------------------------------------------+------------+------------+------------+ [ 0.556833] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.559888] ------------[ cut here ]------------ [ 0.559888] ------------[ cut here ]------------ [ 0.561405] WARNING: CPU: 0 PID: 0 at arch/x86/mm/extable.c:50 ex_handler_rdmsr_unsafe+0x44/0x70 [ 0.561405] WARNING: CPU: 0 PID: 0 at arch/x86/mm/extable.c:50 ex_handler_rdmsr_unsafe+0x44/0x70 [ 0.567649] unchecked MSR access error: RDMSR from 0x1b0 [ 0.567649] unchecked MSR access error: RDMSR from 0x1b0 [ 0.569396] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.7.0-rc3-00232-g5ac0c41 #254 [ 0.569396] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.7.0-rc3-00232-g5ac0c41 #254 [ 0.571860] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 0.571860] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 0.574583] 00200282 [ 0.574583] 00200282 00200282 00200282 81dffe50 81dffe50 812695a2 812695a2 81dffe94 81dffe94 81cda378 81cda378 81dffe80 81dffe80 8105a2de 8105a2de [ 0.586073] 81cda348 [ 0.586073] 81cda348 81dffeb0 81dffeb0 00000000 00000000 81cda378 81cda378 00000032 00000032 81041d84 81041d84 00000032 00000032 81dfff04 81dfff04 [ 0.592643] 81041d40 [ 0.592643] 81041d40 0000000d 0000000d 81dffe9c 81dffe9c 8105a33b 8105a33b 00000009 00000009 00000000 00000000 81dffe94 81dffe94 81cda348 81cda348 [ 0.595200] Call Trace: [ 0.595200] Call Trace: [ 0.595956] [<812695a2>] dump_stack+0xa3/0xf1 [ 0.595956] [<812695a2>] dump_stack+0xa3/0xf1 [ 0.597286] [<8105a2de>] __warn+0xde/0x100 [ 0.597286] [<8105a2de>] __warn+0xde/0x100 [ 0.598536] [<81041d84>] ? ex_handler_rdmsr_unsafe+0x44/0x70 [ 0.598536] [<81041d84>] ? ex_handler_rdmsr_unsafe+0x44/0x70 [ 0.607349] [<81041d40>] ? ex_handler_ext+0x30/0x30 [ 0.607349] [<81041d40>] ? ex_handler_ext+0x30/0x30 [ 0.608880] [<8105a33b>] warn_slowpath_fmt+0x3b/0x40 [ 0.608880] [<8105a33b>] warn_slowpath_fmt+0x3b/0x40 [ 0.610469] [<81041d84>] ex_handler_rdmsr_unsafe+0x44/0x70 [ 0.610469] [<81041d84>] ex_handler_rdmsr_unsafe+0x44/0x70 [ 0.612153] [<81041ea8>] fixup_exception+0x28/0x40 [ 0.612153] [<81041ea8>] fixup_exception+0x28/0x40 [ 0.613642] [<810184e4>] do_general_protection+0x54/0x160 [ 0.613642] [<810184e4>] do_general_protection+0x54/0x160 [ 0.615674] [<8103da30>] ? kvm_setup_secondary_clock+0x30/0x30 [ 0.615674] [<8103da30>] ? kvm_setup_secondary_clock+0x30/0x30 [ 0.617463] [<81018490>] ? do_bounds+0x220/0x220 [ 0.617463] [<81018490>] ? do_bounds+0x220/0x220 [ 0.627326] [<819ec00f>] error_code+0x5f/0x70 [ 0.627326] [<819ec00f>] error_code+0x5f/0x70 [ 0.628639] [<8103da30>] ? kvm_setup_secondary_clock+0x30/0x30 [ 0.628639] [<8103da30>] ? kvm_setup_secondary_clock+0x30/0x30 [ 0.630410] [<81018490>] ? do_bounds+0x220/0x220 [ 0.630410] [<81018490>] ? do_bounds+0x220/0x220 [ 0.631848] [<8103da38>] ? native_read_msr+0x8/0x40 [ 0.631848] [<8103da38>] ? native_read_msr+0x8/0x40 [ 0.633412] [<8102ab53>] init_intel_energy_perf+0x33/0xb0 [ 0.633412] [<8102ab53>] init_intel_energy_perf+0x33/0xb0 [ 0.635016] [<8102b2fb>] init_intel+0x3db/0x440 [ 0.635016] [<8102b2fb>] init_intel+0x3db/0x440 [ 0.644516] [<810296f2>] identify_cpu+0x2f2/0x580 [ 0.644516] [<810296f2>] identify_cpu+0x2f2/0x580 [ 0.645999] [<810aaa52>] ? __raw_spin_lock_init+0x32/0x60 [ 0.645999] [<810aaa52>] ? __raw_spin_lock_init+0x32/0x60 [ 0.647673] [<81f1a507>] identify_boot_cpu+0xd/0x80 [ 0.647673] [<81f1a507>] identify_boot_cpu+0xd/0x80 [ 0.649151] [<81f1a5ac>] check_bugs+0x8/0x36 [ 0.649151] [<81f1a5ac>] check_bugs+0x8/0x36 [ 0.650475] [<81f0fbc7>] start_kernel+0x397/0x3ab [ 0.650475] [<81f0fbc7>] start_kernel+0x397/0x3ab [ 0.651979] [<81f0f2ba>] i386_start_kernel+0xa1/0xa5 [ 0.651979] [<81f0f2ba>] i386_start_kernel+0xa1/0xa5 [ 0.661389] ---[ end trace 30ec2dab0e40afe1 ]--- [ 0.661389] ---[ end trace 30ec2dab0e40afe1 ]--- git bisect start d2cf7f5a55daaccccc46ceec1da2bf67889916b8 5edb56491d4812c42175980759da53388e5d86f5 -- git bisect bad f16530cb909ab2270c1d89a0b4afd12b10229d03 # 19:31 0- 2 Merge 'linux-review/Suraj-Jitindar-Singh/kvm-ppc-book3s_hv-Change-vcore-element-runnable_threads-from-linked-list-to-array/20160615-172538' into devel-catchup-201606151809 git bisect bad f46b8dd9ee264df81124101ae54866a5d62bccc5 # 19:35 0- 3 Merge 'cryptodev/master' into devel-catchup-201606151809 git bisect good 6afb4e72d08fc7000177459ddf2c4e91e3861ce8 # 19:40 22+ 0 Merge 'net-next/master' into devel-catchup-201606151809 git bisect good 77dac5782c0357a273f31cabcc65b8920f261fb4 # 19:47 22+ 1 Merge 'kdave/for-next' into devel-catchup-201606151809 git bisect bad 18b092a7594d2f58fb3558fac018e7e3a9d86b0d # 19:52 0- 22 Merge 'linux-review/Borislav-Petkov/x86-Move-away-from-dev-cpu-msr/20160615-180354' into devel-catchup-201606151809 git bisect bad 5ac0c41bf307ad74185284004062d85a21ecb82b # 19:55 0- 22 x86: Move away from /dev/cpu/*/msr # first bad commit: [5ac0c41bf307ad74185284004062d85a21ecb82b] x86: Move away from /dev/cpu/*/msr git bisect good 0a26309f44946ab08439b87c5456df4f18614cd2 # 19:59 64+ 3 Merge branch 'WIP.futex' # extra tests with CONFIG_DEBUG_INFO_REDUCED git bisect bad 5ac0c41bf307ad74185284004062d85a21ecb82b # 20:02 0- 3 x86: Move away from /dev/cpu/*/msr # extra tests on HEAD of linux-devel/devel-catchup-201606151809 git bisect bad d2cf7f5a55daaccccc46ceec1da2bf67889916b8 # 20:02 0- 13 0day head guard for 'devel-catchup-201606151809' # extra tests on tree/branch linux-review/Borislav-Petkov/x86-Move-away-from-dev-cpu-msr/20160615-180354 git bisect bad 5ac0c41bf307ad74185284004062d85a21ecb82b # 20:10 0- 26 x86: Move away from /dev/cpu/*/msr # extra tests with first bad commit reverted git bisect good adf0526f86dac4a966ee1cbf081645ce13934094 # 20:18 65+ 1 Revert "x86: Move away from /dev/cpu/*/msr" # extra tests on tree/branch linus/master git bisect good db06d759d6cf903aeda8c107fd3abd366dd80200 # 20:21 63+ 1 Merge branch 'for-4.7-fixes' of git://git.kernel.org/pub/scm/linux/kernel/git/tj/percpu # extra tests on tree/branch linux-next/master git bisect good 3a482945b1e6ab568e10347b7759d539c7a9f14f # 20:25 65+ 1 Add linux-next specific files for 20160615 This script may reproduce the error. ---------------------------------------------------------------------------- #!/bin/bash kernel=$1 initrd=quantal-core-i386.cgz wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd kvm=( qemu-system-x86_64 -enable-kvm -cpu kvm64 -kernel $kernel -initrd $initrd -m 300 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -serial stdio -display none -monitor null ) append=( hung_task_panic=1 earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw drbd.minor_count=8 ) "${kvm[@]}" --append "${append[*]}" ---------------------------------------------------------------------------- --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/pipermail/lkp Intel Corporation

4 years, 8 months

5
11
0 / 0

2021

2020

2019

2018

2017

2016

2015

2014

2013

LKP June 2016