Hello
I found blktest srp/011 hang on latest 5.16.0-rc3+, and from dmesg I
can see kernel repeat printing "ib_srpt srpt_disconnect_ch_sync:still
waiting ...".
Pls help check it and let me know if you need any info/testing for it, thanks.
[root@gigabyte-r120-11 blktests]# use_siw=1 ./check srp/011 -------------> hang
srp/011 (Block I/O on top of multipath concurrently with logout and
login) [passed]
runtime 52.731s ... 61.351s
dmesg:
[ 101.614632] run blktests srp/011 at 2021-12-01 03:43:24
[ 102.493106] alua: device handler registered
[ 102.519148] emc: device handler registered
[ 102.540806] rdac: device handler registered
[ 102.608792] null_blk: module loaded
[ 103.031132] SoftiWARP attached
[ 103.067829] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.073399] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.079038] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.093348] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.111956] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.130870] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.141017] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.146585] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.152374] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.166691] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.172623] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.191728] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.197641] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.380984] scsi_debug:sdebug_add_store: dif_storep 524288 bytes @
0000000068763489
[ 103.389445] scsi_debug:sdebug_driver_probe: scsi_debug: trim
poll_queues to 0. poll_q/nr_hw = (0/1)
[ 103.398577] scsi_debug:sdebug_driver_probe: host protection DIF3 DIX3
[ 103.405018] scsi host4: scsi_debug: version 0190 [20200710]
[ 103.405018] dev_size_mb=32, opts=0x0, submit_queues=1, statistics=0
[ 103.417664] scsi 4:0:0:0: Direct-Access Linux scsi_debug
0190 PQ: 0 ANSI: 7
[ 103.426302] sd 4:0:0:0: Power-on or device reset occurred
[ 103.426368] sd 4:0:0:0: Attached scsi generic sg1 type 0
[ 103.431800] sd 4:0:0:0: [sdb] Enabling DIF Type 3 protection
[ 103.442794] sd 4:0:0:0: [sdb] 65536 512-byte logical blocks: (33.6
MB/32.0 MiB)
[ 103.450168] sd 4:0:0:0: [sdb] Write Protect is off
[ 103.454958] sd 4:0:0:0: [sdb] Mode Sense: 73 00 10 08
[ 103.455020] sd 4:0:0:0: [sdb] Write cache: enabled, read cache:
enabled, supports DPO and FUA
[ 103.463665] sd 4:0:0:0: [sdb] Optimal transfer size 524288 bytes
[ 103.567989] sd 4:0:0:0: [sdb] Enabling DIX T10-DIF-TYPE3-CRC protection
[ 103.574602] sd 4:0:0:0: [sdb] DIF application tag size 6
[ 103.757781] sd 4:0:0:0: [sdb] Attached SCSI disk
[ 104.620435] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 104.805722] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 105.168234] Rounding down aligned max_sectors from 4294967295 to 4294967288
[ 105.313416] ib_srpt:srpt_add_one: ib_srpt device = 0000000043289393
[ 105.313438] ib_srpt:srpt_use_srq: ib_srpt
srpt_use_srq(enP2p1s0v0_siw): use_srq = 0; ret[ 101.614632] run
blktests srp/011 at 2021-12-01 03:43:24
[ 102.493106] alua: device handler registered
[ 102.519148] emc: device handler registered
[ 102.540806] rdac: device handler registered
[ 102.608792] null_blk: module loaded
[ 103.031132] SoftiWARP attached
[ 103.067829] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.073399] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.079038] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.093348] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.111956] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.130870] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.141017] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.146585] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.152374] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.166691] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.172623] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.191728] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 103.197641] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 103.380984] scsi_debug:sdebug_add_store: dif_storep 524288 bytes @
0000000068763489
[ 103.389445] scsi_debug:sdebug_driver_probe: scsi_debug: trim
poll_queues to 0. poll_q/nr_hw = (0/1)
[ 103.398577] scsi_debug:sdebug_driver_probe: host protection DIF3 DIX3
[ 103.405018] scsi host4: scsi_debug: version 0190 [20200710]
[ 103.405018] dev_size_mb=32, opts=0x0, submit_queues=1, statistics=0
[ 103.417664] scsi 4:0:0:0: Direct-Access Linux scsi_debug
0190 PQ: 0 ANSI: 7
[ 103.426302] sd 4:0:0:0: Power-on or device reset occurred
[ 103.426368] sd 4:0:0:0: Attached scsi generic sg1 type 0
[ 103.431800] sd 4:0:0:0: [sdb] Enabling DIF Type 3 protection
[ 103.442794] sd 4:0:0:0: [sdb] 65536 512-byte logical blocks: (33.6
MB/32.0 MiB)
[ 103.450168] sd 4:0:0:0: [sdb] Write Protect is off
[ 103.454958] sd 4:0:0:0: [sdb] Mode Sense: 73 00 10 08
[ 103.455020] sd 4:0:0:0: [sdb] Write cache: enabled, read cache:
enabled, supports DPO and FUA
[ 103.463665] sd 4:0:0:0: [sdb] Optimal transfer size 524288 bytes
[ 103.567989] sd 4:0:0:0: [sdb] Enabling DIX T10-DIF-TYPE3-CRC protection
[ 103.574602] sd 4:0:0:0: [sdb] DIF application tag size 6
[ 103.757781] sd 4:0:0:0: [sdb] Attached SCSI disk
[ 104.620435] enP2p1s0v1 speed is unknown, defaulting to 1000
[ 104.805722] enP2p1s0v4 speed is unknown, defaulting to 1000
[ 105.168234] Rounding down aligned max_sectors from 4294967295 to 4294967288
[ 105.313416] ib_srpt:srpt_add_one: ib_srpt device = 0000000043289393
[ 105.313438] ib_srpt:srpt_use_srq: ib_srpt
srpt_use_srq(enP2p1s0v0_siw): use_srq = 0; ret
--snip--
[ 172.857740] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-63
[ 172.857885] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-66
[ 172.858032] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-68
[ 172.858185] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-70
[ 172.858344] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-72
[ 172.858501] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-74
[ 172.858666] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-76
[ 172.858822] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-78
[ 172.858976] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-80
[ 172.859120] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-82
[ 172.859278] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-84
[ 172.859426] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-86
[ 172.859564] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-88
[ 172.859706] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-90
[ 172.859851] ib_srpt:srpt_release_channel_work: ib_srpt
2620:0052:0000:13f0:1e1b:0dff:fe9d:b031-92
[ 173.439406] ib_srpt:srpt_disconnect_ch_sync: ib_srpt ch
2620:0052:0000:13f0:a236:9fff:fe79:eb22-62 state 4
[ 178.456609] ib_srpt
srpt_disconnect_ch_sync(2620:0052:0000:13f0:a236:9fff:fe79:eb22-62
state 4): still waiting ...
[ 183.496506] ib_srpt
srpt_disconnect_ch_sync(2620:0052:0000:13f0:a236:9fff:fe79:eb22-62
state 4): still waiting ...
[ 188.536450] ib_srpt
srpt_disconnect_ch_sync(2620:0052:0000:13f0:a236:9fff:fe79:eb22-62
state 4): still waiting ...
[ 193.576351] ib_srpt
srpt_disconnect_ch_sync(2620:0052:0000:13f0:a236:9fff:fe79:eb22-62
state 4): still waiting ...
[ 198.616280] ib_srpt
srpt_disconnect_ch_sync(2620:0052:0000:13f0:a236:9fff:fe79:eb22-62
state 4): still waiting ...
--
Best Regards,
Yi Zhang
