Hello,
kernel test robot noticed
"WARNING:at_kernel/trace/trace_functions_graph.c:#print_graph_entry" on:
commit: ff5c9c576e754563b3be4922c3968bc3b0269541 ("ftrace: Add support for
function argument to graph tracer")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
[test failed on linus/master b36ddb9210e6812eb1c86ad46b66cc46aa193487]
[test failed on linux-next/master 8566fc3b96539e3235909d6bdda198e1282beaed]
[test failed on fix commit 3b4e87e6a593d571183c414d81758624da01f2b9]
in testcase: ltp
version: ltp-x86_64-037cb53e3-1_20250427
with following parameters:
test: tracing
config: x86_64-rhel-9.4-ltp
compiler: gcc-12
test machine: 96 threads 2 sockets Intel(R) Xeon(R) Platinum 8260L CPU @
2.40GHz (Cascade Lake) with 128G memory
(please refer to attached dmesg/kmsg for entire log/backtrace)
the issue is quite random on ff5c9c576e (and tip of mainline, linux-next/master
and fix commit)
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/test:
lkp-csl-2sp3/ltp/debian-12-x86_64-20240206.cgz/x86_64-rhel-9.4-ltp/gcc-12/tracing
533c20b062d7c25c ff5c9c576e754563b3be4922c39
---------------- ---------------------------
fail:runs %reproduction fail:runs
| | |
:12 8% 1:12
dmesg.WARNING:at_kernel/trace/trace_functions_graph.c:#print_graph_entry
If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.s...@intel.com>
| Closes: https://lore.kernel.org/oe-lkp/202505221008.e9da5421-...@intel.com
The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20250522/202505221008.e9da5421-...@intel.com
[ 165.619427][T12912] ------------[ cut here ]------------
[ 165.625655][T12912] WARNING: CPU: 73 PID: 12912 at
kernel/trace/trace_functions_graph.c:988 print_graph_entry+0xce0/0x1300
[ 165.637562][T12912] Modules linked in: kmem intel_rapl_msr intel_rapl_common
intel_uncore_frequency intel_uncore_frequency_common skx_edac skx_edac_common
x86_pkg_temp_thermal btrfs intel_powerclamp blake2b_generic xor zstd_compress
raid6_pq coretemp sr_mod device_dax sd_mod cdrom irdma kvm_intel nd_pmem nd_btt
dax_pmem sg ice kvm snd_pcm ast snd_timer ghash_clmulni_intel gnss ahci
ib_uverbs snd drm_client_lib rapl libahci ipmi_ssif nvme drm_shmem_helper
soundcore intel_cstate binfmt_misc acpi_ipmi ipmi_si intel_uncore nvme_core
i2c_i801 ib_core libata drm_kms_helper pcspkr ioatdma nfit ipmi_devintf wmi
intel_pch_thermal lpc_ich i2c_smbus dca ipmi_msghandler libnvdimm joydev drm
fuse loop dm_mod ip_tables
[ 165.705994][T12912] CPU: 73 UID: 0 PID: 12912 Comm: cat Tainted: G S
6.14.0-rc4-00004-gff5c9c576e75 #1
[ 165.717794][T12912] Tainted: [S]=CPU_OUT_OF_SPEC
[ 165.723501][T12912] Hardware name: Intel Corporation S2600WFD/S2600WFD, BIOS
SE5C620.86B.0D.01.0286.011120190816 01/11/2019
[ 165.735795][T12912] RIP: 0010:print_graph_entry+0xce0/0x1300
[ 165.742549][T12912] Code: 80 3c 02 00 0f 85 30 04 00 00 b8 03 00 00 00 48 39
9d e8 1f 00 00 0f 83 64 fb ff ff 31 c0 e9 5d fb ff ff 0f 0b e9 cb f8 ff ff <0f>
0b e9 51 fe ff ff 48 89 7c 24 08 4c 89 cf 4c 89 0c 24 e8 a8 4e
[ 165.764354][T12912] RSP: 0018:ffffc9003d89f9b0 EFLAGS: 00010282
[ 165.771507][T12912] RAX: ffffe8b160826a40 RBX: ffff88d109a18000 RCX:
0000000083f7eee0
[ 165.780604][T12912] RDX: ffffffff85671a20 RSI: 0000000000000000 RDI:
ffffe8b160826a44
[ 165.789720][T12912] RBP: ffff88d109a1a090 R08: 0000000000000002 R09:
ffff88d109a18018
[ 165.798844][T12912] R10: 0000000000000004 R11: ffff88d109a1c0b0 R12:
0000000000000696
[ 165.807979][T12912] R13: ffff88df621fab00 R14: 1ffff92007b13f3f R15:
0000000083f7eee0
[ 165.817150][T12912] FS: 00007f659f541740(0000) GS:ffff88dcdd280000(0000)
knlGS:0000000000000000
[ 165.827310][T12912] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 165.835106][T12912] CR2: 00007f659f4bf000 CR3: 00000050f4ffc004 CR4:
00000000007726f0
[ 165.844337][T12912] DR0: 0000000000000000 DR1: 0000000000000000 DR2:
0000000000000000
[ 165.853569][T12912] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7:
0000000000000400
[ 165.862804][T12912] PKRU: 55555554
[ 165.867605][T12912] Call Trace:
[ 165.872146][T12912] <TASK>
[ 165.876283][T12912] ? __warn+0xcd/0x260
[ 165.881552][T12912] ? print_graph_entry+0xce0/0x1300
[ 165.887970][T12912] ? report_bug+0x25d/0x2c0
[ 165.893692][T12912] ? handle_bug+0x53/0xa0
[ 165.899241][T12912] ? exc_invalid_op+0x13/0x40
[ 165.905135][T12912] ? asm_exc_invalid_op+0x16/0x20
[ 165.911505][T12912] ? print_graph_entry+0xce0/0x1300
[ 165.917994][T12912] ? __pfx_print_graph_entry+0x10/0x10
[ 165.924697][T12912] ? _raw_spin_lock+0x81/0xe0
[ 165.930599][T12912] ? __pfx___raise_softirq_irqoff+0x10/0x10
[ 165.937762][T12912] ? __pfx__raw_spin_lock+0x10/0x10
[ 165.944250][T12912] ? ring_buffer_empty_cpu+0x173/0x2b0
[ 165.950994][T12912] print_graph_function_flags+0x2cf/0x610
[ 165.958048][T12912] print_trace_line+0x20e/0x1100
[ 165.964280][T12912] ? __might_resched+0x5/0x450
[ 165.970343][T12912] ? __pfx_print_trace_line+0x10/0x10
[ 165.977036][T12912] ? __pfx_down_write+0x10/0x10
[ 165.983237][T12912] tracing_read_pipe+0x47d/0xb80
[ 165.989543][T12912] ? rw_verify_area+0x69/0x410
[ 165.995652][T12912] vfs_read+0x1c4/0x900
[ 166.001148][T12912] ? __pfx_vfs_read+0x10/0x10
[ 166.007113][T12912] ? ftrace_graph_func+0xd6/0x150
[ 166.013420][T12912] ? 0xffffffffc18f1095
[ 166.018807][T12912] ? __pfx_ksys_read+0x10/0x10
[ 166.024781][T12912] ksys_read+0xf0/0x1c0
[ 166.030080][T12912] ? __pfx_ksys_read+0x10/0x10
[ 166.035971][T12912] do_syscall_64+0x79/0x150
[ 166.041512][T12912] ? __traceiter_sys_exit+0x2e/0x50
[ 166.047771][T12912] ? syscall_exit_to_user_mode_prepare+0x148/0x1d0
[ 166.056578][T12912] ? syscall_exit_to_user_mode+0xc/0x1e0
[ 166.063627][T12912] ? do_syscall_64+0x85/0x150
[ 166.069685][T12912] ? clear_bhb_loop+0x25/0x80
[ 166.075673][T12912] ? clear_bhb_loop+0x25/0x80
[ 166.081550][T12912] ? clear_bhb_loop+0x25/0x80
[ 166.087388][T12912] entry_SYSCALL_64_after_hwframe+0x76/0x7e
[ 166.094409][T12912] RIP: 0033:0x7f659f63c25d
[ 166.099911][T12912] Code: 31 c0 e9 c6 fe ff ff 50 48 8d 3d a6 53 0a 00 e8 59
ff 01 00 66 0f 1f 84 00 00 00 00 00 80 3d 81 23 0e 00 00 74 17 31 c0 0f 05 <48>
3d 00 f0 ff ff 77 5b c3 66 2e 0f 1f 84 00 00 00 00 00 48 83 ec
[ 166.122019][T12912] RSP: 002b:00007ffc61ee1208 EFLAGS: 00000246 ORIG_RAX:
0000000000000000
[ 166.131713][T12912] RAX: ffffffffffffffda RBX: 0000000000020000 RCX:
00007f659f63c25d
[ 166.140937][T12912] RDX: 0000000000020000 RSI: 00007f659f4be000 RDI:
0000000000000004
[ 166.150183][T12912] RBP: 0000000000020000 R08: 00000000ffffffff R09:
0000000000000000
[ 166.159396][T12912] R10: 00007f659f55d4f0 R11: 0000000000000246 R12:
00007f659f4be000
[ 166.168591][T12912] R13: 0000000000000004 R14: 0000000000020000 R15:
0000000000000000
[ 166.177863][T12912] </TASK>
[ 166.182080][T12912] ---[ end trace 0000000000000000 ]---
[ 170.117417][ T1336] Modules Loaded kmem intel_rapl_msr
intel_rapl_common intel_uncore_frequency intel_uncore_frequency_common skx_edac
skx_edac_common x86_pkg_temp_thermal btrfs intel_powerclamp blake2b_generic xor
zstd_compress raid6_pq coretemp sr_mod device_dax sd_mod cdrom irdma kvm_intel
nd_pmem nd_btt dax_pmem sg ice kvm snd_pcm ast snd_timer ghash_clmulni_intel
gnss ahci ib_uverbs snd drm_client_lib rapl libahci ipmi_ssif nvme
drm_shmem_helper soundcore intel_cstate binfmt_misc acpi_ipmi ipmi_si
intel_uncore nvme_core i2c_i801 ib_core libata drm_kms_helper pcspkr ioatdma
nfit ipmi_devintf wmi intel_pch_thermal lpc_ich i2c_smbus dca ipmi_msghandler
libnvdimm joydev drm fuse loop dm_mod ip_tables
[ 170.117436][ T1336]
[ 170.204150][ T1336]
--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki
