[bigeasy-staging:module_v3] [bpf] 1c7701f3dd: WARNING:at_kernel/module/main.c:#module_put

[bigeasy-staging:module_v3] [bpf] 1c7701f3dd: WARNING:at_kernel/module/main.c:#module_put

[kernel test robot]

Hello,

kernel test robot noticed "WARNING:at_kernel/module/main.c:#module_put" on:

commit: 1c7701f3dda185212b05ce57b783ec9f40d599ee ("bpf: Use RCU in all users of __module_text_address().")
https://git.kernel.org/cgit/linux/kernel/git/bigeasy/staging.git module_v3

in testcase: kernel-selftests-bpf
version: 
with following parameters:

	group: bpf



config: x86_64-rhel-9.4-bpf
compiler: gcc-12
test machine: 8 threads 1 sockets Intel(R) Core(TM) i7-7700 CPU @ 3.60GHz (Kaby Lake) with 32G memory

(please refer to attached dmesg/kmsg for entire log/backtrace)


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.sang@intel.com>
| Closes: https://lore.kernel.org/oe-lkp/202501260918.fb95ce00-lkp@intel.com



[ 2192.902410][T62425] ------------[ cut here ]------------
[ 2192.907741][T62425] WARNING: CPU: 5 PID: 62425 at kernel/module/main.c:875 module_put+0x48/0x60
[ 2192.917080][T62425] Modules linked in: tun ipip tunnel4 ip_tunnel iptable_raw xt_connmark bpf_testmod(OE) veth cls_bpf sch_ingress rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver openvswitch nf_conncount nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 psample snd_hda_codec_hdmi snd_ctl_led snd_hda_codec_realtek snd_hda_codec_generic snd_hda_scodec_component intel_rapl_msr intel_rapl_common intel_uncore_frequency intel_uncore_frequency_common btrfs blake2b_generic xor zstd_compress raid6_pq snd_soc_avs libcrc32c snd_soc_hda_codec snd_hda_ext_core x86_pkg_temp_thermal i915 intel_powerclamp sd_mod coretemp dell_pc dell_wmi snd_soc_core sg cec kvm_intel snd_compress dell_smbios drm_buddy kvm ttm snd_hda_intel snd_intel_dspcfg drm_display_helper snd_intel_sdw_acpi crct10dif_pclmul snd_hda_codec crc32_pclmul ipmi_devintf crc32c_intel drm_kms_helper ghash_clmulni_intel ipmi_msghandler snd_hda_core mei_wdt intel_gtt dell_wmi_aio wmi_bmof snd_hwdep agpgart rapl i2c_designware_platform sparse_keymap dell_wmi_descriptor
[ 2192.917382][T62425]  i2c_designware_core ahci snd_pcm intel_cstate video platform_profile dcdbas snd_timer libahci mei_me intel_uncore pcspkr i2c_i801 intel_lpss_pci intel_pmc_core snd intel_lpss i2c_smbus soundcore libata mei idma64 intel_vsec wmi pinctrl_sunrisepoint pmt_telemetry acpi_pad pmt_class binfmt_misc drm dm_mod ip_tables x_tables sch_fq_codel [last unloaded: bpf_test_modorder_x(OE)]
[ 2193.042104][T62425] CPU: 5 UID: 0 PID: 62425 Comm: test_progs Tainted: G S      W  OE      6.13.0-rc6-00025-g1c7701f3dda1 #1
[ 2193.053378][T62425] Tainted: [S]=CPU_OUT_OF_SPEC, [W]=WARN, [O]=OOT_MODULE, [E]=UNSIGNED_MODULE
[ 2193.062114][T62425] Hardware name: Dell Inc. OptiPlex 7050/062KRH, BIOS 1.2.0 12/22/2016
[ 2193.070263][T62425] RIP: 0010:module_put+0x48/0x60
[ 2193.075695][T62425] Code: 48 89 df e8 5a f1 3f 00 8b 85 80 05 00 00 89 c2 83 ea 01 78 15 f0 0f b1 13 75 f3 48 8b 74 24 10 48 89 ef 5b 5d e9 a8 fe ff ff <0f> 0b 48 8b 74 24 10 48 89 ef 5b 5d e9 97 fe ff ff 0f 1f 80 00 00
[ 2193.095187][T62425] RSP: 0018:ffff88826a227ac0 EFLAGS: 00010297
[ 2193.101136][T62425] RAX: 0000000000000000 RBX: ffffffffc13e1c40 RCX: dffffc0000000000
[ 2193.108990][T62425] RDX: 00000000ffffffff RSI: ffffffff812c8e26 RDI: ffffffffc13e1c40
[ 2193.116847][T62425] RBP: ffffffffc13e16c0 R08: 0000000000000001 R09: fffffbfff827c388
[ 2193.124718][T62425] R10: ffffffffc13e1c43 R11: 0000000000000000 R12: ffff8881827f5018
[ 2193.132575][T62425] R13: ffff8881886d1710 R14: 0000000000000010 R15: ffffffff82f9d240
[ 2193.140446][T62425] FS:  00007fe81554f0c0(0000) GS:ffff888733880000(0000) knlGS:0000000000000000
[ 2193.149261][T62425] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 2193.155744][T62425] CR2: 000056474c61f000 CR3: 00000003c5a90005 CR4: 00000000003726f0
[ 2193.163599][T62425] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 2193.171467][T62425] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 2193.179320][T62425] Call Trace:
[ 2193.182504][T62425]  <TASK>
[ 2193.185328][T62425]  ? module_put+0x48/0x60
[ 2193.190150][T62425]  ? __warn+0x9d/0x140
[ 2193.194104][T62425]  ? module_put+0x48/0x60
[ 2193.198934][T62425]  ? report_bug+0x1a6/0x1d0
[ 2193.203331][T62425]  ? handle_bug+0x53/0xa0
[ 2193.207544][T62425]  ? exc_invalid_op+0x13/0x40
[ 2193.212106][T62425]  ? asm_exc_invalid_op+0x16/0x20
[ 2193.217030][T62425]  ? module_put+0x26/0x60
[ 2193.221855][T62425]  ? module_put+0x48/0x60
[ 2193.226682][T62425]  bpf_kprobe_multi_link_release+0x50/0x60
[ 2193.232375][T62425]  bpf_link_free+0x79/0x110
[ 2193.236763][T62425]  bpf_link_release+0x31/0x40
[ 2193.241324][T62425]  __fput+0x1eb/0x4b0
[ 2193.245205][T62425]  __x64_sys_close+0x50/0x90
[ 2193.249695][T62425]  do_syscall_64+0x8c/0x170
[ 2193.254088][T62425]  ? lock_acquire+0x123/0x2e0
[ 2193.258670][T62425]  ? __might_fault+0x74/0xc0
[ 2193.263153][T62425]  ? find_held_lock+0x83/0xa0
[ 2193.267716][T62425]  ? __might_fault+0x74/0xc0
[ 2193.272195][T62425]  ? __lock_release+0x130/0x260
[ 2193.277536][T62425]  ? __might_fault+0x74/0xc0
[ 2193.282018][T62425]  ? __pfx___lock_release+0x10/0x10
[ 2193.287728][T62425]  ? __might_fault+0x74/0xc0
[ 2193.292211][T62425]  ? rcu_is_watching+0x1c/0x50
[ 2193.296872][T62425]  ? trace_rseq_update+0xb9/0xf0
[ 2193.301700][T62425]  ? __rseq_handle_notify_resume+0x321/0x3a0
[ 2193.307578][T62425]  ? __pfx___rseq_handle_notify_resume+0x10/0x10
[ 2193.313791][T62425]  ? __x64_sys_close+0x50/0x90
[ 2193.318483][T62425]  ? rcu_is_watching+0x1c/0x50
[ 2193.323132][T62425]  ? mark_held_locks+0x24/0x90
[ 2193.327787][T62425]  ? lockdep_hardirqs_on_prepare+0x131/0x200
[ 2193.333655][T62425]  ? syscall_exit_to_user_mode+0xa2/0x2a0
[ 2193.339262][T62425]  ? do_syscall_64+0x98/0x170
[ 2193.343829][T62425]  ? mark_held_locks+0x24/0x90
[ 2193.348481][T62425]  ? lockdep_hardirqs_on_prepare+0x131/0x200
[ 2193.354346][T62425]  ? syscall_exit_to_user_mode+0xa2/0x2a0
[ 2193.359949][T62425]  ? do_syscall_64+0x98/0x170
[ 2193.364512][T62425]  ? mark_held_locks+0x24/0x90
[ 2193.369163][T62425]  ? lockdep_hardirqs_on_prepare+0x131/0x200
[ 2193.375031][T62425]  entry_SYSCALL_64_after_hwframe+0x76/0x7e
[ 2193.380822][T62425] RIP: 0033:0x7fe81564898a
[ 2193.385122][T62425] Code: 48 3d 00 f0 ff ff 77 48 c3 0f 1f 80 00 00 00 00 48 83 ec 18 89 7c 24 0c e8 a3 ce f8 ff 8b 7c 24 0c 89 c2 b8 03 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 36 89 d7 89 44 24 0c e8 03 cf f8 ff 8b 44 24
[ 2193.404603][T62425] RSP: 002b:00007ffd9c026160 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
[ 2193.412908][T62425] RAX: ffffffffffffffda RBX: 00007ffd9c026578 RCX: 00007fe81564898a
[ 2193.420776][T62425] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 000000000000002c
[ 2193.428632][T62425] RBP: 00007ffd9c026190 R08: 0027c793795a4932 R09: 0000000000000064
[ 2193.436485][T62425] R10: 00007fe815a96000 R11: 0000000000000293 R12: 0000000000000000
[ 2193.444339][T62425] R13: 00007ffd9c026588 R14: 0000564738b8b110 R15: 00007fe815ace020
[ 2193.452238][T62425]  </TASK>
[ 2193.455144][T62425] irq event stamp: 21447965
[ 2193.459529][T62425] hardirqs last  enabled at (21447977): [<ffffffff81261e72>] __up_console_sem+0x52/0x60
[ 2193.469127][T62425] hardirqs last disabled at (21447994): [<ffffffff81261e57>] __up_console_sem+0x37/0x60
[ 2193.478724][T62425] softirqs last  enabled at (21447990): [<ffffffff81154a85>] handle_softirqs+0x255/0x370
[ 2193.488423][T62425] softirqs last disabled at (21447985): [<ffffffff81154d39>] __irq_exit_rcu+0x129/0x150
[ 2193.498016][T62425] ---[ end trace 0000000000000000 ]---
[ 2193.503378][T62425] ------------[ cut here ]------------
[ 2193.508718][T62425] WARNING: CPU: 5 PID: 62425 at kernel/module/main.c:875 module_put+0x48/0x60
[ 2193.518100][T62425] Modules linked in: tun ipip tunnel4 ip_tunnel iptable_raw xt_connmark bpf_testmod(OE) veth cls_bpf sch_ingress rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver openvswitch nf_conncount nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 psample snd_hda_codec_hdmi snd_ctl_led snd_hda_codec_realtek snd_hda_codec_generic snd_hda_scodec_component intel_rapl_msr intel_rapl_common intel_uncore_frequency intel_uncore_frequency_common btrfs blake2b_generic xor zstd_compress raid6_pq snd_soc_avs libcrc32c snd_soc_hda_codec snd_hda_ext_core x86_pkg_temp_thermal i915 intel_powerclamp sd_mod coretemp dell_pc dell_wmi snd_soc_core sg cec kvm_intel snd_compress dell_smbios drm_buddy kvm ttm snd_hda_intel snd_intel_dspcfg drm_display_helper snd_intel_sdw_acpi crct10dif_pclmul snd_hda_codec crc32_pclmul ipmi_devintf crc32c_intel drm_kms_helper ghash_clmulni_intel ipmi_msghandler snd_hda_core mei_wdt intel_gtt dell_wmi_aio wmi_bmof snd_hwdep agpgart rapl i2c_designware_platform sparse_keymap dell_wmi_descriptor


... <very long similar dmesg here>


[ 3821.303878][T62425] ------------[ cut here ]------------
[ 3821.309187][T62425] WARNING: CPU: 5 PID: 62425 at kernel/module/main.c:875 module_put+0x48/0x60
[ 3821.318480][T62425] Modules linked in: tun ipip tunnel4 ip_tunnel iptable_raw xt_connmark bpf_testmod(OE) veth cls_bpf sch_ingress rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver openvswitch nf_conncount nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 psample snd_hda_codec_hdmi snd_ctl_led snd_hda_codec_realtek snd_hda_codec_generic snd_hda_scodec_component intel_rapl_msr intel_rapl_common intel_uncore_frequency intel_uncore_frequency_common btrfs blake2b_generic xor zstd_compress raid6_pq snd_soc_avs libcrc32c snd_soc_hda_codec snd_hda_ext_core x86_pkg_temp_thermal i915 intel_powerclamp sd_mod coretemp dell_pc dell_wmi snd_soc_core sg cec kvm_intel snd_compress dell_smbios drm_buddy kvm ttm snd_hda_intel snd_intel_dspcfg drm_display_helper snd_intel_sdw_acpi crct10dif_pclmul snd_hda_codec crc32_pclmul ipmi_devintf crc32c_intel drm_kms_helper ghash_clmulni_intel ipmi_msghandler snd_hda_core mei_wdt intel_gtt dell_wmi_aio wmi_bmof snd_hwdep agpgart rapl i2c_designware_platform sparse_keymap dell_wmi_descriptor
[ 3821.318709][T62425]  i2c_designware_core ahci snd_pcm intel_cstate video platform_profile dcdbas snd_timer libahci mei_me intel_uncore pcspkr i2c_i801 intel_lpss_pci intel_pmc_core snd intel_lpss i2c_smbus soundcore libata mei idma64 intel_vsec wmi pinctrl_sunrisepoint pmt_telemetry acpi_pad pmt_class binfmt_misc drm dm_mod ip_tables x_tables sch_fq_codel [last unloaded: bpf_test_modorder_x(OE)]
[ 3821.442859][T62425] CPU: 5 UID: 0 PID: 62425 Comm: test_progs Tainted: G S      W  OE      6.13.0-rc6-00025-g1c7701f3dda1 #1
[ 3821.454053][T62425] Tainted: [S]=CPU_OUT_OF_SPEC, [W]=WARN, [O]=OOT_MODULE, [E]=UNSIGNED_MODULE
[ 3821.462742][T62425] Hardware name: Dell Inc. OptiPlex 7050/062KRH, BIOS 1.2.0 12/22/2016
[ 3821.470839][T62425] RIP: 0010:module_put+0x48/0x60
[ 3821.476272][T62425] Code: 48 89 df e8 5a f1 3f 00 8b 85 80 05 00 00 89 c2 83 ea 01 78 15 f0 0f b1 13 75 f3 48 8b 74 24 10 48 89 ef 5b 5d e9 a8 fe ff ff <0f> 0b 48 8b 74 24 10 48 89 ef 5b 5d e9 97 fe ff ff 0f 1f 80 00 00
[ 3821.495686][T62425] RSP: 0018:ffff88826a227ac0 EFLAGS: 00010297
[ 3821.501603][T62425] RAX: 0000000000000000 RBX: ffffffffc06fae00 RCX: dffffc0000000000
[ 3821.509424][T62425] RDX: 00000000ffffffff RSI: ffffffff812c8e26 RDI: ffffffffc06fae00
[ 3821.517273][T62425] RBP: ffffffffc06fa880 R08: 0000000000000001 R09: fffffbfff80df5c0
[ 3821.525094][T62425] R10: ffffffffc06fae03 R11: 0000000000000000 R12: ffff8881827f5018
[ 3821.532915][T62425] R13: ffff8881886d1710 R14: 0000000000000010 R15: ffffffff82f9d240
[ 3821.540740][T62425] FS:  00007fe81554f0c0(0000) GS:ffff888733880000(0000) knlGS:0000000000000000
[ 3821.549511][T62425] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 3821.555959][T62425] CR2: 000056474c61f000 CR3: 00000003c5a90005 CR4: 00000000003726f0
[ 3821.563798][T62425] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 3821.571635][T62425] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 3821.579460][T62425] Call Trace:
[ 3821.582610][T62425]  <TASK>
[ 3821.585415][T62425]  ? module_put+0x48/0x60
[ 3821.590212][T62425]  ? __warn+0x9d/0x140
[ 3821.594146][T62425]  ? module_put+0x48/0x60
[ 3821.598948][T62425]  ? report_bug+0x1a6/0x1d0
[ 3821.603345][T62425]  ? handle_bug+0x53/0xa0
[ 3821.607544][T62425]  ? exc_invalid_op+0x13/0x40
[ 3821.612088][T62425]  ? asm_exc_invalid_op+0x16/0x20
[ 3821.616998][T62425]  ? module_put+0x26/0x60
[ 3821.621804][T62425]  ? module_put+0x48/0x60
[ 3821.626616][T62425]  bpf_kprobe_multi_link_release+0x50/0x60
[ 3821.632316][T62425]  bpf_link_free+0x79/0x110
[ 3821.636684][T62425]  bpf_link_release+0x31/0x40
[ 3821.641223][T62425]  __fput+0x1eb/0x4b0
[ 3821.645085][T62425]  __x64_sys_close+0x50/0x90
[ 3821.649537][T62425]  do_syscall_64+0x8c/0x170
[ 3821.653911][T62425]  ? lock_acquire+0x123/0x2e0
[ 3821.658446][T62425]  ? __might_fault+0x74/0xc0
[ 3821.662902][T62425]  ? find_held_lock+0x83/0xa0
[ 3821.667443][T62425]  ? __might_fault+0x74/0xc0
[ 3821.671899][T62425]  ? __lock_release+0x130/0x260
[ 3821.677214][T62425]  ? __might_fault+0x74/0xc0
[ 3821.681670][T62425]  ? __pfx___lock_release+0x10/0x10
[ 3821.687364][T62425]  ? __might_fault+0x74/0xc0
[ 3821.691820][T62425]  ? rcu_is_watching+0x1c/0x50
[ 3821.696442][T62425]  ? trace_rseq_update+0xb9/0xf0
[ 3821.701276][T62425]  ? __rseq_handle_notify_resume+0x321/0x3a0
[ 3821.707117][T62425]  ? __pfx___rseq_handle_notify_resume+0x10/0x10
[ 3821.713338][T62425]  ? __x64_sys_close+0x50/0x90
[ 3821.717964][T62425]  ? rcu_is_watching+0x1c/0x50
[ 3821.722587][T62425]  ? mark_held_locks+0x24/0x90
[ 3821.727232][T62425]  ? lockdep_hardirqs_on_prepare+0x131/0x200
[ 3821.733108][T62425]  ? syscall_exit_to_user_mode+0xa2/0x2a0
[ 3821.738685][T62425]  ? do_syscall_64+0x98/0x170
[ 3821.743232][T62425]  ? mark_held_locks+0x24/0x90
[ 3821.747894][T62425]  ? lockdep_hardirqs_on_prepare+0x131/0x200
[ 3821.753745][T62425]  ? syscall_exit_to_user_mode+0xa2/0x2a0
[ 3821.759339][T62425]  ? do_syscall_64+0x98/0x170
[ 3821.763892][T62425]  ? mark_held_locks+0x24/0x90
[ 3821.768523][T62425]  ? lockdep_hardirqs_on_prepare+0x131/0x200
[ 3821.774378][T62425]  entry_SYSCALL_64_after_hwframe+0x76/0x7e
[ 3821.780140][T62425] RIP: 0033:0x7fe81564898a
[ 3821.784430][T62425] Code: 48 3d 00 f0 ff ff 77 48 c3 0f 1f 80 00 00 00 00 48 83 ec 18 89 7c 24 0c e8 a3 ce f8 ff 8b 7c 24 0c 89 c2 b8 03 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 36 89 d7 89 44 24 0c e8 03 cf f8 ff 8b 44 24
[ 3821.803863][T62425] RSP: 002b:00007ffd9c026160 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
[ 3821.812146][T62425] RAX: ffffffffffffffda RBX: 00007ffd9c026578 RCX: 00007fe81564898a
[ 3821.819967][T62425] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 000000000000002c
[ 3821.827803][T62425] RBP: 00007ffd9c026190 R08: 0027c793795a4932 R09: 0000000000000064
[ 3821.835628][T62425] R10: 00007fe815a96000 R11: 0000000000000293 R12: 0000000000000000
[ 3821.843457][T62425] R13: 00007ffd9c026588 R14: 0000564738b8b110 R15: 00007fe815ace020
[ 3821.851347][T62425]  </TASK>
[ 3821.854299][T62425] irq event stamp: 31037781
[ 3821.858666][T62425] hardirqs last  enabled at (31037799): [<ffffffff81261e72>] __up_console_sem+0x52/0x60
[ 3821.868277][T62425] hardirqs last disabled at (31037828): [<ffffffff81261e57>] __up_console_sem+0x37/0x60
[ 3821.877847][T62425] softirqs last  enabled at (31037842): [<ffffffff81154a85>] handle_softirqs+0x255/0x370
[ 3821.887553][T62425] softirqs last disabled at (31037855): [<ffffffff81154d39>] __irq_exit_rcu+0x129/0x150


The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20250126/202501260918.fb95ce00-lkp@intel.com


-- 
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki