-----"Yi Zhang" <yi.zhang@xxxxxxxxxx> wrote: ----- >To: "RDMA mailing list" <linux-rdma@xxxxxxxxxxxxxxx> >From: "Yi Zhang" <yi.zhang@xxxxxxxxxx> >Date: 12/01/2021 09:55AM >Subject: [EXTERNAL] [bug report] blktests srp/011 hang at "ib_srpt >srpt_disconnect_ch_sync:still waiting ..." > >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. > Is this bug happening only when using siw, or also happening with rxe? I'll try to recreate. Thanks, Bernard. >[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 > >
