Bug 2052452

Summary: WARNING: CPU: 35 PID: 3629639 at block/bfq-iosched.c:602 bfq_limit_depth+0x478/0x4f0
Product: [Fedora] Fedora Reporter: ChanghuiZhong <czhong>
Component: mdadmAssignee: Jes Sorensen <jes.sorensen>
Status: NEW --- QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 37CC: agk, dledford, jes.sorensen, xni
Target Milestone: ---   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:

Description ChanghuiZhong 2022-02-09 10:08:58 UTC 
Description of problem:
When running the swraid trim test, warning log appear

[17268.283718] ------------[ cut here ]------------ 
[17268.307825] WARNING: CPU: 21 PID: 2870188 at block/bfq-iosched.c:606 bfq_limit_depth+0x4a6/0x4f0 
[17268.350734] Modules linked in: raid1 raid0 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx raid6_pq dm_cache_smq dm_cache dm_thin_pool dm_persistent_data dm_bio_prison dm_bufio nvme ipmi_watchdog ipmi_poweroff ipmi_ssif ipmi_devintf nls_utf8 cifs cifs_arc4 cifs_md4 veth bridge stp llc vsock_loopback vmw_vsock_virtio_transport_common vmw_vsock_vmci_transport vsock vmw_vmci loop tun af_key crypto_user scsi_transport_iscsi ip_gre ip_tunnel gre bluetooth ecdh_generic ecc overlay ah6 ah4 nft_objref nft_ct nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 nf_tables nfnetlink vfat fat sctp ip6_udp_tunnel udp_tunnel rpcsec_gss_krb5 nfsv4 dns_resolver nfs fscache netfs rpcrdma rdma_cm iw_cm ib_cm ib_core nfsd auth_rpcgss nfs_acl lockd grace dm_log_writes dm_mod tls rfkill sunrpc intel_rapl_msr intel_rapl_common sb_edac x86_pkg_temp_thermal intel_powerclamp ext4 coretemp kvm_intel mbcache jbd2 kvm irqbypass iTCO_wdt iTCO_vendor_support rapl ioatdma acpi_ipmi bnx2x intel_cstate ipmi_si 
[17268.350860]  tg3 ipmi_msghandler pcspkr mdio dca intel_uncore i2c_i801 lpc_ich acpi_power_meter i2c_smbus acpi_tad hpilo fuse zram xfs libcrc32c mgag200 i2c_algo_bit sr_mod drm_shmem_helper qla2xxx drm_kms_helper cdrom sd_mod syscopyarea sysfillrect sysimgblt fb_sys_fops nvme_fc cec ahci libahci nvme_fabrics crct10dif_pclmul crc32_pclmul crc32c_intel nvme_core libata serio_raw t10_pi drm ghash_clmulni_intel hpsa scsi_transport_fc hpwdt scsi_transport_sas wmi [last unloaded: scsi_debug] 
[17268.947930] CPU: 21 PID: 2870188 Comm: kworker/u81:6 Tainted: G           OE  X --------- ---  5.17.0-0.rc3.89.test.eln.x86_64 #1 
[17269.000443] Hardware name: HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 02/17/2017 
[17269.037532] Workqueue: loop0 loop_rootcg_workfn [loop] 
[17269.060613] RIP: 0010:bfq_limit_depth+0x4a6/0x4f0 
[17269.081939] Code: fd ff ff 45 8b 46 40 44 0f b6 cb 31 f6 48 c7 c2 5c 82 a1 97 48 c7 c1 81 82 a1 97 31 c0 41 55 e8 a0 1b cf ff 48 83 c4 08 eb 9d <0f> 0b e9 7e fd ff ff 48 8b 7c 24 10 e8 79 83 00 00 48 89 c7 e8 51 
[17269.166305] RSP: 0018:ffffad291a7f3700 EFLAGS: 00010097 
[17269.189717] RAX: 0000000000000001 RBX: 0000000000000060 RCX: ffff8c6602828920 
[17269.221920] RDX: ffff8c696fa00000 RSI: ffff8c696fbf1340 RDI: ffff8c660282a430 
[17269.254065] RBP: ffffad291a7f3838 R08: ffff8c696faf1340 R09: 0000000000580000 
[17269.286259] R10: 0000000000580000 R11: ffffffff96b521e0 R12: ffffad291a7f38a8 
[17269.319940] R13: ffffad291a7f3780 R14: 0000000000000001 R15: 0000000000000002 
[17269.356585] FS:  0000000000000000(0000) GS:ffff8c696fac0000(0000) knlGS:0000000000000000 
[17269.394169] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033 
[17269.419943] CR2: 00007f839ebc5dd0 CR3: 00000005a7a10004 CR4: 00000000001706e0 
[17269.453300] Call Trace: 
[17269.465046]  <TASK> 
[17269.474415]  ? bfq_setup_cooperator+0xd4/0x270 
[17269.494355]  ? bfq_allow_bio_merge+0x67/0xb0 
[17269.513453]  ? elv_merge+0xad/0x150 
[17269.528690]  ? bfq_request_merge+0x3c/0x90 
[17269.547156]  ? blk_mq_sched_try_merge+0x33/0x1b0 
[17269.567842]  __blk_mq_alloc_requests+0x75/0x240 
[17269.588296]  blk_mq_submit_bio+0x1fa/0x500 
[17269.606284]  submit_bio_noacct+0x23c/0x290 
[17269.624721]  submit_bio+0x66/0x110 
[17269.639954]  iomap_do_writepage+0x729/0x8e0 
[17269.658716]  write_cache_pages+0x262/0x3b0 
[17269.676831]  ? iomap_writepage+0x90/0x90 
[17269.694491]  iomap_writepages+0x1f/0x90 
[17269.711666]  xfs_vm_writepages+0xd2/0x100 [xfs] 
[17269.732076]  do_writepages+0xdb/0x210 
[17269.748515]  filemap_fdatawrite_wbc+0x61/0x80 
[17269.767992]  file_write_and_wait_range+0xb6/0x120 
rk+0x1b7/0x8b0 [loop] 
[17269.831198]  ? pick_next_task_fair+0x100/0x320 
[17269.851186]  ? __switch_to+0x145/0x520 
[17269.868008]  ? finish_task_switch+0x11b/0x2f0 
[17269.887588]  process_one_work+0x1a1/0x350 
[17269.905666]  worker_thread+0x270/0x430 
[17269.922588]  ? worker_clr_flags+0x50/0x50 
[17269.940531]  kthread+0xd8/0xf0 
[17269.954620]  ? kthread_blkcg+0x30/0x30 
[17269.971401]  ret_from_fork+0x22/0x30 
[17269.987433]  </TASK> 
[17269.997190] ---[ end trace 0000000000000000 ]--- 

Version-Release number of selected component (if applicable):
5.17.0-0.rc3.89.test.eln.x86_64

Kernel repo: https://gitlab.com/cki-project/kernel-ark.git
Commit: fda54bec37dd - [redhat] kernel-5.17.0-0.rc3.89

How reproducible:
100%

Steps to Reproduce:
1.run test case https://gitlab.com/cki-project/kernel-tests/-/tree/main/storage/swraid/trim
2.
3.

Actual results:
no issues

Expected results:
warning log appear

Additional info:
I originally want to report this issue to Block Layer, but I couldn't find this component, so I report it to mdadm

https://beaker.engineering.redhat.com/recipes/11428066#tasks
https://beaker-archive.host.prod.eng.bos.redhat.com/beaker-logs/2022/02/62801/6280134/11428066/console.log
Comment 1 Ben Cotton 2022-08-09 13:12:43 UTC 
This bug appears to have been reported against 'rawhide' during the Fedora Linux 37 development cycle.
Changing version to 37.