Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Configured reservation key doesn't match: 0x0 #92

Closed
ajaincdsc opened this issue Jul 10, 2024 · 17 comments · Fixed by #94
Closed

Configured reservation key doesn't match: 0x0 #92

ajaincdsc opened this issue Jul 10, 2024 · 17 comments · Fixed by #94

Comments

@ajaincdsc
Copy link

ajaincdsc commented Jul 10, 2024

Hello,

I can't seem to narrow down this error. On running this command:

/usr/sbin/mpathpersist -o --preempt-abort --prout-type=5 --param-rk=1 --param-sark=2 -d /dev/mapper/san0

I receive this error:

san0: configured reservation key doesn't match: 0x0

However, 0x0 doesn't match any configured keys:

  PR generation=0x42,   12 registered reservation keys follow:
    0x2
    0x2
    0x1
    0x1
    0x2
    0x2
    0x1
    0x1
    0x1
    0x1
    0x2
    0x2

There are 2 hosts running a Pacemaker stack using fence_mpath to generate the commands, with the keys 1 and 2 respectively. Other commands seem to work fine, I'm just unable to figure out why there's a failure. I have tried manually releasing the reservation, but it ends up at the same place eventually.

I am running Debian 12 with multipath-tools 0.9.4

Any help is appreciated, thanks!

@mwilck
Copy link
Collaborator

mwilck commented Jul 10, 2024

Check your reservation_key setting in multipath.conf.

@ajaincdsc
Copy link
Author

ajaincdsc commented Jul 10, 2024

multipaths {
        multipath {
                wwid "----"
                alias san0
                reservation_key 0x2
        }
}

looks correct on both nodes (the other one is 0x1), unless I'm missing something?

@ajaincdsc
Copy link
Author

I feel like I have a lead on this, but I'm not sure. I found a thread from 2010 that suggests that all 6 active round robin ports that hold a particular active key (e.g. 0x1) should be able to access the SCSI device at the same time, however, when the scripts run, only 1 path out of 6 becomes a reservation holder. Does anyone have any hints as to what I should change to fix this behaviour?

% sudo mpathpersist -i -s /dev/mapper/san0
  PR generation=0x42 
   Key = 0x2
      All target ports bit clear
      Relative port address: 0x201
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 48
   Key = 0x2
      All target ports bit clear
      Relative port address: 0x202
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 49
   Key = 0x1
      All target ports bit clear
      Relative port address: 0x101
      << Reservation holder >>
      scope: LU_SCOPE,  type: Write Exclusive, registrants only
   FCP-2         xx xx xx xx xx xx xx 54
   Key = 0x2
      All target ports bit clear
      Relative port address: 0x101
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 48
   Key = 0x2
      All target ports bit clear
      Relative port address: 0x301
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 48
   Key = 0x1
      All target ports bit clear
      Relative port address: 0x201
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 54
   Key = 0x1
      All target ports bit clear
      Relative port address: 0x301
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 54
   Key = 0x1
      All target ports bit clear
      Relative port address: 0x302
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 55
   Key = 0x1
      All target ports bit clear
      Relative port address: 0x102
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 55
   Key = 0x1
      All target ports bit clear
      Relative port address: 0x202
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 55
   Key = 0x2
      All target ports bit clear
      Relative port address: 0x102
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 49
   Key = 0x2
      All target ports bit clear
      Relative port address: 0x302
      not reservation holder
   FCP-2         xx xx xx xx xx xx xx 49

@mwilck
Copy link
Collaborator

mwilck commented Jul 15, 2024

To my understanding, all paths with the same key as the reservation holder (0x1) should be able to access the LUN. Otherwise I'd assume that multipathd would show them as failed. Can you confirm?

@ajaincdsc
Copy link
Author

:~ % sudo multipath -ll
san0 (----) dm-10 NFINIDAT,InfiniBox
size=6.0T features='0' hwhandler='1 alua' wp=rw
`-+- policy='round-robin 0' prio=50 status=active
  |- 11:0:0:11 sdb 8:16  active ready running
  |- 13:0:0:11 sde 8:64  active ready running
  |- 11:0:1:11 sdk 8:160 active ready running
  |- 13:0:1:11 sdi 8:128 active ready running
  |- 11:0:2:11 sdg 8:96  active ready running
  `- 13:0:2:11 sda 8:0   active ready running

Sorry, wasn't sure how to confirm that. Does this help?

@mwilck
Copy link
Collaborator

mwilck commented Jul 15, 2024

Sorry, I was mistaken. According to the SCSI spec, with reservation type 5 (Write exclusive / registrants only), only one I/T nexus is the reservation holder. Write access should be allowed for every registered I_T nexus (i.e. both nodes; you could test this by writing to the SCSI devices but that would of course be dangerous). So what you report above is correct, AFAICT.

Can you try running the above mpathpersist command with -v4?
I should add that I have little experience with cluster configurations, perhaps @bmarzins will see more clearly than me what's going wrong.

@ajaincdsc
Copy link
Author

Thanks for the help! Here's the output, though I apologize for how long it is...
There are 6 Seagate drives on the host that we've blacklisted from multipath, which show up in the output. I've left them in but can trim if preferred.

very long code block
  267090.932379 | set open fds limit to 1048576/1048576
  267090.932481 | loading /lib/multipath/libchecktur.so checker
  267090.932635 | checker tur: message table size = 3
  267090.932656 | loading /lib/multipath/libprioconst.so prioritizer
  267090.932807 | _init_foreign: found libforeign-nvme.so
  267090.932824 | _init_foreign: foreign library "nvme" is not enabled
  267090.957090 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:2/0:0:2:0/block/sdc
  267090.957222 | 8:32: dev_t not found in pathvec
  267090.957269 | sdc: mask = 0x27
  267090.957276 | sdc: dev_t = 8:32
  267090.957282 | open '/sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:2/0:0:2:0/block/sdc/size'
  267090.957309 | sdc: size = 2344225968
  267090.957518 | sdc: vendor = SEAGATE
  267090.957546 | sdc: product = ST1200MM0088
  267090.957571 | sdc: rev = TT31
  267090.958238 | find_hwe: found 0 hwtable matches for SEAGATE:ST1200MM0088:TT31
  267090.958247 | sdc: h:b:t:l = 0:0:2:0
  267090.958589 | sdc: tgt_node_name = 
  267090.958598 | sdc: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.958603 | sdc: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.958755 | sdc: udev property ID_WWN whitelisted
  267090.958800 | sdc: protocol scsi:unspec blacklisted
  267090.958827 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:3/0:0:3:0/block/sdm
  267090.958946 | 8:192: dev_t not found in pathvec
  267090.958978 | sdm: mask = 0x27
  267090.958984 | sdm: dev_t = 8:192
  267090.958989 | open '/sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:3/0:0:3:0/block/sdm/size'
  267090.959017 | sdm: size = 2344225968
  267090.959194 | sdm: vendor = SEAGATE
  267090.959219 | sdm: product = ST1200MM0088
  267090.959243 | sdm: rev = TT31
  267090.959739 | find_hwe: found 0 hwtable matches for SEAGATE:ST1200MM0088:TT31
  267090.959747 | sdm: h:b:t:l = 0:0:3:0
  267090.960088 | sdm: tgt_node_name = 
  267090.960097 | sdm: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.960106 | sdm: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.960248 | sdm: udev property ID_WWN whitelisted
  267090.960259 | sdm: protocol scsi:unspec blacklisted
  267090.960294 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:4/0:0:4:0/block/sdf
  267090.960424 | 8:80: dev_t not found in pathvec
  267090.960465 | sdf: mask = 0x27
  267090.960474 | sdf: dev_t = 8:80
  267090.960482 | open '/sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:4/0:0:4:0/block/sdf/size'
  267090.960510 | sdf: size = 2344225968
  267090.960688 | sdf: vendor = SEAGATE
  267090.960712 | sdf: product = ST1200MM0088
  267090.960736 | sdf: rev = TT31
  267090.961242 | find_hwe: found 0 hwtable matches for SEAGATE:ST1200MM0088:TT31
  267090.961251 | sdf: h:b:t:l = 0:0:4:0
  267090.961580 | sdf: tgt_node_name = 
  267090.961588 | sdf: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.961593 | sdf: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.961728 | sdf: udev property ID_WWN whitelisted
  267090.961738 | sdf: protocol scsi:unspec blacklisted
  267090.961774 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:5/0:0:5:0/block/sdj
  267090.961905 | 8:144: dev_t not found in pathvec
  267090.961945 | sdj: mask = 0x27
  267090.961954 | sdj: dev_t = 8:144
  267090.961961 | open '/sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:5/0:0:5:0/block/sdj/size'
  267090.961990 | sdj: size = 2344225968
  267090.962229 | sdj: vendor = SEAGATE
  267090.962274 | sdj: product = ST1200MM0088
  267090.962304 | sdj: rev = TT31
  267090.962770 | find_hwe: found 0 hwtable matches for SEAGATE:ST1200MM0088:TT31
  267090.962777 | sdj: h:b:t:l = 0:0:5:0
  267090.963102 | sdj: tgt_node_name = 
  267090.963110 | sdj: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.963116 | sdj: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.963249 | sdj: udev property ID_WWN whitelisted
  267090.963259 | sdj: protocol scsi:unspec blacklisted
  267090.963291 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:6/0:0:6:0/block/sdh
  267090.963414 | 8:112: dev_t not found in pathvec
  267090.963453 | sdh: mask = 0x27
  267090.963462 | sdh: dev_t = 8:112
  267090.963468 | open '/sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:6/0:0:6:0/block/sdh/size'
  267090.963493 | sdh: size = 2344225968
  267090.963669 | sdh: vendor = SEAGATE
  267090.963701 | sdh: product = ST1200MM0088
  267090.963730 | sdh: rev = TT31
  267090.964199 | find_hwe: found 0 hwtable matches for SEAGATE:ST1200MM0088:TT31
  267090.964207 | sdh: h:b:t:l = 0:0:6:0
  267090.964513 | sdh: tgt_node_name = 
  267090.964520 | sdh: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.964526 | sdh: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.964657 | sdh: udev property ID_WWN whitelisted
  267090.964667 | sdh: protocol scsi:unspec blacklisted
  267090.964692 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:7/0:0:7:0/block/sdn
  267090.964807 | 8:208: dev_t not found in pathvec
  267090.964836 | sdn: mask = 0x27
  267090.964842 | sdn: dev_t = 8:208
  267090.964847 | open '/sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:0:7/0:0:7:0/block/sdn/size'
  267090.964867 | sdn: size = 2344225968
  267090.965038 | sdn: vendor = SEAGATE
  267090.965062 | sdn: product = ST1200MM0088
  267090.965085 | sdn: rev = TT31
  267090.965546 | find_hwe: found 0 hwtable matches for SEAGATE:ST1200MM0088:TT31
  267090.965553 | sdn: h:b:t:l = 0:0:7:0
  267090.965858 | sdn: tgt_node_name = 
  267090.965865 | sdn: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.965870 | sdn: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.965990 | sdn: udev property ID_WWN whitelisted
  267090.966000 | sdn: protocol scsi:unspec blacklisted
  267090.966084 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:2:0/0:2:0:0/block/sdl
  267090.966200 | 8:176: dev_t not found in pathvec
  267090.966230 | sdl: mask = 0x27
  267090.966236 | sdl: dev_t = 8:176
  267090.966241 | open '/sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:2:0/0:2:0:0/block/sdl/size'
  267090.966261 | sdl: size = 780140544
  267090.966425 | sdl: vendor = DELL
  267090.966449 | sdl: product = PERC H730 Mini
  267090.966471 | sdl: rev = 4.30
  267090.966932 | find_hwe: found 0 hwtable matches for DELL:PERC H730 Mini:4.30
  267090.966939 | sdl: h:b:t:l = 0:2:0:0
  267090.967249 | sdl: tgt_node_name = 
  267090.967256 | sdl: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.967261 | sdl: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.967368 | sdl: udev property ID_WWN whitelisted
  267090.967397 | sdl: protocol scsi:unspec blacklisted
  267090.967422 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:2:0/0:2:0:0/block/sdl/sdl1
  267090.967543 | Discover device /sys/devices/pci0000:00/0000:00:01.0/0000:02:00.0/host0/target0:2:0/0:2:0:0/block/sdl/sdl2
  267090.967653 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd
  267090.967775 | 8:48: dev_t not found in pathvec
  267090.967805 | sdd: mask = 0x27
  267090.967811 | sdd: dev_t = 8:48
  267090.967816 | open '/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/size'
  267090.967843 | sdd: size = 31293440
  267090.968067 | sdd: vendor = DELL
  267090.968093 | sdd: product = IDSDM
  267090.968118 | sdd: rev = 0001
  267090.968587 | find_hwe: found 0 hwtable matches for DELL:IDSDM:0001
  267090.968595 | sdd: h:b:t:l = 12:0:0:0
  267090.968866 | sdd: tgt_node_name = 1-1.3:1.0
  267090.968875 | sdd: skip USB device 1-1.3:1.0
  267090.968889 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/sdd1
  267090.969033 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/sdd2
  267090.969164 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/sdd4
  267090.969291 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/sdd5
  267090.969418 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/sdd6
  267090.969544 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/sdd7
  267090.969670 | Discover device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/host12/target12:0:0/12:0:0:0/block/sdd/sdd8
  267090.969795 | Discover device /sys/devices/pci0000:00/0000:00:1f.2/ata10/host10/target10:0:0/10:0:0:0/block/sr0
  267090.969895 | 11:0: dev_t not found in pathvec
  267090.969925 | sr0: device node name blacklisted
  267090.969932 | Discover device /sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/block/sdb
  267090.970083 | 8:16: dev_t not found in pathvec
  267090.970113 | sdb: mask = 0x27
  267090.970118 | sdb: dev_t = 8:16
  267090.970125 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/block/sdb/size'
  267090.970155 | sdb: size = 12884901888
  267090.970341 | sdb: vendor = NFINIDAT
  267090.970366 | sdb: product = InfiniBox
  267090.970389 | sdb: rev = 0h
  267090.970504 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267090.970986 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267090.970992 | sdb: h:b:t:l = 11:0:0:11
  267090.971478 | SCSI target 11:0:0 -> FC rport 11:0-4
  267090.971509 | sdb: tgt_node_name = 0x5742b0f0000a0900
  267090.971514 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.971521 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.971654 | sdb: udev property ID_WWN whitelisted
  267090.971741 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/state'
  267090.971764 | sdb: path state = running
  267090.971804 | sdb: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267090.971810 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267090.971817 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/vpd_pg80'
  267090.971841 | sdb: serial = 742b0f000000a0900000000000b2a87
  267090.971849 | sdb: detect_checker = yes (setting: multipath internal)
  267090.975447 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/inquiry'
  267090.975470 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/vpd_pg83'
  267090.975636 | sdb: path_checker = tur (setting: storage device autodetected)
  267090.975647 | sdb: checker timeout = 30 s (setting: kernel sysfs)
  267090.975749 | sdb: tur state = up
  267090.975757 | Discover device /sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/block/sdk
  267090.975875 | 8:160: dev_t not found in pathvec
  267090.975910 | sdk: mask = 0x27
  267090.975916 | sdk: dev_t = 8:160
  267090.975923 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/block/sdk/size'
  267090.975950 | sdk: size = 12884901888
  267090.976145 | sdk: vendor = NFINIDAT
  267090.976170 | sdk: product = InfiniBox
  267090.976194 | sdk: rev = 0h
  267090.976313 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267090.976755 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267090.976761 | sdk: h:b:t:l = 11:0:1:11
  267090.977250 | SCSI target 11:0:1 -> FC rport 11:0-5
  267090.977279 | sdk: tgt_node_name = 0x5742b0f0000a0900
  267090.977285 | sdk: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.977291 | sdk: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.977426 | sdk: udev property ID_WWN whitelisted
  267090.977444 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/state'
  267090.977469 | sdk: path state = running
  267090.977487 | sdk: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267090.977494 | sdk: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267090.977503 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/vpd_pg80'
  267090.977523 | sdk: serial = 742b0f000000a0900000000000b2a87
  267090.977531 | sdk: detect_checker = yes (setting: multipath internal)
  267090.980254 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/inquiry'
  267090.980275 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/vpd_pg83'
  267090.980489 | sdk: path_checker = tur (setting: storage device autodetected)
  267090.980501 | sdk: checker timeout = 30 s (setting: kernel sysfs)
  267090.980600 | sdk: tur state = up
  267090.980607 | Discover device /sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/block/sdg
  267090.980724 | 8:96: dev_t not found in pathvec
  267090.980753 | sdg: mask = 0x27
  267090.980759 | sdg: dev_t = 8:96
  267090.980766 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/block/sdg/size'
  267090.980792 | sdg: size = 12884901888
  267090.980973 | sdg: vendor = NFINIDAT
  267090.980997 | sdg: product = InfiniBox
  267090.981038 | sdg: rev = 0h
  267090.981164 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267090.981642 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267090.981648 | sdg: h:b:t:l = 11:0:2:11
  267090.982142 | SCSI target 11:0:2 -> FC rport 11:0-6
  267090.982221 | sdg: tgt_node_name = 0x5742b0f0000a0900
  267090.982229 | sdg: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.982236 | sdg: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.982368 | sdg: udev property ID_WWN whitelisted
  267090.982382 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/state'
  267090.982410 | sdg: path state = running
  267090.982431 | sdg: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267090.982438 | sdg: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267090.982445 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/vpd_pg80'
  267090.982470 | sdg: serial = 742b0f000000a0900000000000b2a87
  267090.982475 | sdg: detect_checker = yes (setting: multipath internal)
  267090.985108 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/inquiry'
  267090.985129 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/vpd_pg83'
  267090.985339 | sdg: path_checker = tur (setting: storage device autodetected)
  267090.985350 | sdg: checker timeout = 30 s (setting: kernel sysfs)
  267090.985460 | sdg: tur state = up
  267090.985467 | Discover device /sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/block/sde
  267090.985585 | 8:64: dev_t not found in pathvec
  267090.985615 | sde: mask = 0x27
  267090.985621 | sde: dev_t = 8:64
  267090.985628 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/block/sde/size'
  267090.985656 | sde: size = 12884901888
  267090.985836 | sde: vendor = NFINIDAT
  267090.985860 | sde: product = InfiniBox
  267090.985883 | sde: rev = 0h
  267090.985994 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267090.986432 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267090.986439 | sde: h:b:t:l = 13:0:0:11
  267090.986914 | SCSI target 13:0:0 -> FC rport 13:0-4
  267090.986943 | sde: tgt_node_name = 0x5742b0f0000a0900
  267090.986949 | sde: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.986954 | sde: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.987133 | sde: udev property ID_WWN whitelisted
  267090.987149 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/state'
  267090.987177 | sde: path state = running
  267090.987199 | sde: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267090.987205 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267090.987212 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/vpd_pg80'
  267090.987231 | sde: serial = 742b0f000000a0900000000000b2a87
  267090.987237 | sde: detect_checker = yes (setting: multipath internal)
  267090.989808 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/inquiry'
  267090.989860 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/vpd_pg83'
  267090.990094 | sde: path_checker = tur (setting: storage device autodetected)
  267090.990103 | sde: checker timeout = 30 s (setting: kernel sysfs)
  267090.990211 | sde: tur state = up
  267090.990219 | Discover device /sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/block/sdi
  267090.990336 | 8:128: dev_t not found in pathvec
  267090.990365 | sdi: mask = 0x27
  267090.990371 | sdi: dev_t = 8:128
  267090.990378 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/block/sdi/size'
  267090.990405 | sdi: size = 12884901888
  267090.990586 | sdi: vendor = NFINIDAT
  267090.990611 | sdi: product = InfiniBox
  267090.990634 | sdi: rev = 0h
  267090.990749 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267090.991264 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267090.991272 | sdi: h:b:t:l = 13:0:1:11
  267090.991749 | SCSI target 13:0:1 -> FC rport 13:0-5
  267090.991778 | sdi: tgt_node_name = 0x5742b0f0000a0900
  267090.991784 | sdi: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.991791 | sdi: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.991921 | sdi: udev property ID_WWN whitelisted
  267090.991936 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/state'
  267090.991962 | sdi: path state = running
  267090.991984 | sdi: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267090.991991 | sdi: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267090.991998 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/vpd_pg80'
  267090.992028 | sdi: serial = 742b0f000000a0900000000000b2a87
  267090.992034 | sdi: detect_checker = yes (setting: multipath internal)
  267090.994338 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/inquiry'
  267090.994358 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/vpd_pg83'
  267090.994544 | sdi: path_checker = tur (setting: storage device autodetected)
  267090.994555 | sdi: checker timeout = 30 s (setting: kernel sysfs)
  267090.994660 | sdi: tur state = up
  267090.994667 | Discover device /sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/block/sda
  267090.994783 | 8:0: dev_t not found in pathvec
  267090.994813 | sda: mask = 0x27
  267090.994819 | sda: dev_t = 8:0
  267090.994825 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/block/sda/size'
  267090.994852 | sda: size = 12884901888
  267090.995040 | sda: vendor = NFINIDAT
  267090.995065 | sda: product = InfiniBox
  267090.995114 | sda: rev = 0h
  267090.995230 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267090.995675 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267090.995682 | sda: h:b:t:l = 13:0:2:11
  267090.996176 | SCSI target 13:0:2 -> FC rport 13:0-6
  267090.996215 | sda: tgt_node_name = 0x5742b0f0000a0900
  267090.996223 | sda: uid_attribute = ID_SERIAL (setting: multipath internal)
  267090.996230 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
  267090.996364 | sda: udev property ID_WWN whitelisted
  267090.996382 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/state'
  267090.996406 | sda: path state = running
  267090.996425 | sda: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267090.996431 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267090.996441 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/vpd_pg80'
  267090.996464 | sda: serial = 742b0f000000a0900000000000b2a87
  267090.996470 | sda: detect_checker = yes (setting: multipath internal)
  267090.998648 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/inquiry'
  267090.998668 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/vpd_pg83'
  267090.998848 | sda: path_checker = tur (setting: storage device autodetected)
  267090.998859 | sda: checker timeout = 30 s (setting: kernel sysfs)
  267090.998959 | sda: tur state = up
  267090.998967 | Discover device /sys/devices/virtual/block/loop0
  267090.999050 | 7:0: dev_t not found in pathvec
  267090.999077 | loop0: device node name blacklisted
  267090.999084 | Discover device /sys/devices/virtual/block/loop1
  267090.999153 | 7:1: dev_t not found in pathvec
  267090.999178 | loop1: device node name blacklisted
  267090.999185 | Discover device /sys/devices/virtual/block/loop2
  267090.999252 | 7:2: dev_t not found in pathvec
  267090.999276 | loop2: device node name blacklisted
  267090.999283 | Discover device /sys/devices/virtual/block/loop3
  267090.999359 | 7:3: dev_t not found in pathvec
  267090.999394 | loop3: device node name blacklisted
  267090.999405 | Discover device /sys/devices/virtual/block/loop4
  267090.999476 | 7:4: dev_t not found in pathvec
  267090.999501 | loop4: device node name blacklisted
  267090.999508 | Discover device /sys/devices/virtual/block/loop5
  267090.999573 | 7:5: dev_t not found in pathvec
  267090.999597 | loop5: device node name blacklisted
  267090.999604 | Discover device /sys/devices/virtual/block/loop6
  267090.999678 | 7:6: dev_t not found in pathvec
  267090.999711 | loop6: device node name blacklisted
  267090.999722 | Discover device /sys/devices/virtual/block/loop7
  267090.999791 | 7:7: dev_t not found in pathvec
  267090.999816 | loop7: device node name blacklisted
  267090.999823 | Discover device /sys/devices/virtual/block/dm-0
  267090.999891 | 254:0: dev_t not found in pathvec
  267090.999917 | dm-0: device node name blacklisted
  267090.999924 | Discover device /sys/devices/virtual/block/dm-1
  267090.999991 | 254:1: dev_t not found in pathvec
  267091.000026 | dm-1: device node name blacklisted
  267091.000034 | Discover device /sys/devices/virtual/block/dm-10
  267091.000102 | 254:10: dev_t not found in pathvec
  267091.000126 | dm-10: device node name blacklisted
  267091.000133 | Discover device /sys/devices/virtual/block/dm-11
  267091.000204 | 254:11: dev_t not found in pathvec
  267091.000238 | dm-11: device node name blacklisted
  267091.000251 | Discover device /sys/devices/virtual/block/dm-2
  267091.000323 | 254:2: dev_t not found in pathvec
  267091.000349 | dm-2: device node name blacklisted
  267091.000356 | Discover device /sys/devices/virtual/block/dm-3
  267091.000422 | 254:3: dev_t not found in pathvec
  267091.000446 | dm-3: device node name blacklisted
  267091.000453 | Discover device /sys/devices/virtual/block/dm-4
  267091.000526 | 254:4: dev_t not found in pathvec
  267091.000559 | dm-4: device node name blacklisted
  267091.000570 | Discover device /sys/devices/virtual/block/dm-5
  267091.000653 | 254:5: dev_t not found in pathvec
  267091.000684 | dm-5: device node name blacklisted
  267091.000692 | Discover device /sys/devices/virtual/block/dm-6
  267091.000768 | 254:6: dev_t not found in pathvec
  267091.000798 | dm-6: device node name blacklisted
  267091.000808 | Discover device /sys/devices/virtual/block/dm-7
  267091.000879 | 254:7: dev_t not found in pathvec
  267091.000904 | dm-7: device node name blacklisted
  267091.000910 | Discover device /sys/devices/virtual/block/dm-8
  267091.000983 | 254:8: dev_t not found in pathvec
  267091.001023 | dm-8: device node name blacklisted
  267091.001032 | Discover device /sys/devices/virtual/block/dm-9
  267091.001109 | 254:9: dev_t not found in pathvec
  267091.001140 | dm-9: device node name blacklisted
  267091.001148 | Discovered 6/35 paths
  ===== paths list =====
  uuid hcil      dev dev_t pri dm_st chk_st vend/prod/rev      dev_st 
      11:0:0:11 sdb 8:16  -1  undef undef  NFINIDAT,InfiniBox unknown
      11:0:1:11 sdk 8:160 -1  undef undef  NFINIDAT,InfiniBox unknown
      11:0:2:11 sdg 8:96  -1  undef undef  NFINIDAT,InfiniBox unknown
      13:0:0:11 sde 8:64  -1  undef undef  NFINIDAT,InfiniBox unknown
      13:0:1:11 sdi 8:128 -1  undef undef  NFINIDAT,InfiniBox unknown
      13:0:2:11 sda 8:0   -1  undef undef  NFINIDAT,InfiniBox unknown
  267091.009629 | multipath-tools v0.9.4 (12/19, 2022)
  267091.009643 | libdevmapper version 1.02.185
  267091.009777 | kernel device mapper v4.47.0
  267091.009794 | DM multipath kernel driver v1.14.0
  267091.009981 | san0: disassemble map [0 1 alua 1 1 round-robin 0 6 1 8:16 1 8:64 1 8:160 1 8:128 1 8:96 1 8:0 1 ]
  267091.010035 | san0: disassemble status [2 0 1 0 1 1 A 0 6 0 8:16 A 0 8:64 A 0 8:160 A 0 8:128 A 0 8:96 A 0 8:0 A 0 ]
  267091.010051 | sdb: mask = 0x1f
  267091.010060 | sdb: dev_t = 8:16
  267091.010066 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/block/sdb/size'
  267091.010093 | sdb: size = 12884901888
  267091.010101 | sdb: vendor = NFINIDAT
  267091.010106 | sdb: product = InfiniBox
  267091.010110 | sdb: rev = 0h
  267091.010206 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267091.010644 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267091.010650 | sdb: h:b:t:l = 11:0:0:11
  267091.010778 | SCSI target 11:0:0 -> FC rport 11:0-4
  267091.010806 | sdb: tgt_node_name = 0x5742b0f0000a0900
  267091.010813 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/state'
  267091.010839 | sdb: path state = running
  267091.010847 | sdb: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267091.010853 | sdb: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267091.010860 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/vpd_pg80'
  267091.010877 | sdb: serial = 742b0f000000a0900000000000b2a87
  267091.010975 | sdb: tur state = up
  267091.010986 | sdb: uid = 36742b0f000000a0900000000000b2a87 (udev)
  267091.010993 | sdb: detect_prio = yes (setting: multipath internal)
  267091.013308 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/access_state'
  267091.013334 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/preferred_path'
  267091.013357 | loading /lib/multipath/libpriosysfs.so prioritizer
  267091.013449 | sdb: prio = sysfs (setting: storage device autodetected)
  267091.013456 | sdb: prio args = "" (setting: storage device autodetected)
  267091.013466 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/access_state'
  267091.013495 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-4/target11:0:0/11:0:0:11/preferred_path'
  267091.013519 | sdb: sysfs prio = 50
  267091.013530 | sde: mask = 0x1f
  267091.013539 | sde: dev_t = 8:64
  267091.013546 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/block/sde/size'
  267091.013575 | sde: size = 12884901888
  267091.013585 | sde: vendor = NFINIDAT
  267091.013593 | sde: product = InfiniBox
  267091.013597 | sde: rev = 0h
  267091.013691 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267091.014133 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267091.014141 | sde: h:b:t:l = 13:0:0:11
  267091.014270 | SCSI target 13:0:0 -> FC rport 13:0-4
  267091.014299 | sde: tgt_node_name = 0x5742b0f0000a0900
  267091.014305 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/state'
  267091.014330 | sde: path state = running
  267091.014342 | sde: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267091.014347 | sde: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267091.014354 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/vpd_pg80'
  267091.014373 | sde: serial = 742b0f000000a0900000000000b2a87
  267091.014520 | sde: tur state = up
  267091.014527 | sde: uid = 36742b0f000000a0900000000000b2a87 (udev)
  267091.014532 | sde: detect_prio = yes (setting: multipath internal)
  267091.016739 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/access_state'
  267091.016803 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/preferred_path'
  267091.016843 | sde: prio = sysfs (setting: storage device autodetected)
  267091.016848 | sde: prio args = "" (setting: storage device autodetected)
  267091.016856 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/access_state'
  267091.016877 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-4/target13:0:0/13:0:0:11/preferred_path'
  267091.016897 | sde: sysfs prio = 50
  267091.016909 | sdk: mask = 0x1f
  267091.016914 | sdk: dev_t = 8:160
  267091.016921 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/block/sdk/size'
  267091.016948 | sdk: size = 12884901888
  267091.016958 | sdk: vendor = NFINIDAT
  267091.016964 | sdk: product = InfiniBox
  267091.016968 | sdk: rev = 0h
  267091.017066 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267091.017499 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267091.017506 | sdk: h:b:t:l = 11:0:1:11
  267091.017634 | SCSI target 11:0:1 -> FC rport 11:0-5
  267091.017663 | sdk: tgt_node_name = 0x5742b0f0000a0900
  267091.017670 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/state'
  267091.017694 | sdk: path state = running
  267091.017705 | sdk: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267091.017711 | sdk: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267091.017721 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/vpd_pg80'
  267091.017745 | sdk: serial = 742b0f000000a0900000000000b2a87
  267091.017841 | sdk: tur state = up
  267091.017852 | sdk: uid = 36742b0f000000a0900000000000b2a87 (udev)
  267091.017858 | sdk: detect_prio = yes (setting: multipath internal)
  267091.020043 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/access_state'
  267091.020111 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/preferred_path'
  267091.020157 | sdk: prio = sysfs (setting: storage device autodetected)
  267091.020171 | sdk: prio args = "" (setting: storage device autodetected)
  267091.020185 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/access_state'
  267091.020228 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-5/target11:0:1/11:0:1:11/preferred_path'
  267091.020270 | sdk: sysfs prio = 50
  267091.020289 | sdi: mask = 0x1f
  267091.020303 | sdi: dev_t = 8:128
  267091.020315 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/block/sdi/size'
  267091.020392 | sdi: size = 12884901888
  267091.020418 | sdi: vendor = NFINIDAT
  267091.020450 | sdi: product = InfiniBox
  267091.020456 | sdi: rev = 0h
  267091.020548 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267091.020978 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267091.020984 | sdi: h:b:t:l = 13:0:1:11
  267091.021118 | SCSI target 13:0:1 -> FC rport 13:0-5
  267091.021148 | sdi: tgt_node_name = 0x5742b0f0000a0900
  267091.021154 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/state'
  267091.021182 | sdi: path state = running
  267091.021194 | sdi: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267091.021200 | sdi: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267091.021207 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/vpd_pg80'
  267091.021226 | sdi: serial = 742b0f000000a0900000000000b2a87
  267091.021338 | sdi: tur state = up
  267091.021346 | sdi: uid = 36742b0f000000a0900000000000b2a87 (udev)
  267091.021351 | sdi: detect_prio = yes (setting: multipath internal)
  267091.023638 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/access_state'
  267091.023702 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/preferred_path'
  267091.023755 | sdi: prio = sysfs (setting: storage device autodetected)
  267091.023760 | sdi: prio args = "" (setting: storage device autodetected)
  267091.023768 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/access_state'
  267091.023789 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-5/target13:0:1/13:0:1:11/preferred_path'
  267091.023809 | sdi: sysfs prio = 50
  267091.023820 | sdg: mask = 0x1f
  267091.023826 | sdg: dev_t = 8:96
  267091.023831 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/block/sdg/size'
  267091.023852 | sdg: size = 12884901888
  267091.023859 | sdg: vendor = NFINIDAT
  267091.023864 | sdg: product = InfiniBox
  267091.023868 | sdg: rev = 0h
  267091.023959 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267091.024399 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267091.024406 | sdg: h:b:t:l = 11:0:2:11
  267091.024541 | SCSI target 11:0:2 -> FC rport 11:0-6
  267091.024577 | sdg: tgt_node_name = 0x5742b0f0000a0900
  267091.024586 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/state'
  267091.024611 | sdg: path state = running
  267091.024620 | sdg: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267091.024627 | sdg: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267091.024634 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/vpd_pg80'
  267091.024652 | sdg: serial = 742b0f000000a0900000000000b2a87
  267091.024755 | sdg: tur state = up
  267091.024766 | sdg: uid = 36742b0f000000a0900000000000b2a87 (udev)
  267091.024773 | sdg: detect_prio = yes (setting: multipath internal)
  267091.026875 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/access_state'
  267091.026955 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/preferred_path'
  267091.027038 | sdg: prio = sysfs (setting: storage device autodetected)
  267091.027068 | sdg: prio args = "" (setting: storage device autodetected)
  267091.027077 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/access_state'
  267091.027102 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.0/host11/rport-11:0-6/target11:0:2/11:0:2:11/preferred_path'
  267091.027125 | sdg: sysfs prio = 50
  267091.027134 | sda: mask = 0x1f
  267091.027138 | sda: dev_t = 8:0
  267091.027146 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/block/sda/size'
  267091.027172 | sda: size = 12884901888
  267091.027182 | sda: vendor = NFINIDAT
  267091.027189 | sda: product = InfiniBox
  267091.027197 | sda: rev = 0h
  267091.027297 | find_hwe: found match /NFINIDAT:InfiniBox:(null)/ for 'NFINIDAT:InfiniBox:0h'
  267091.027734 | find_hwe: found 1 hwtable matches for NFINIDAT:InfiniBox:0h
  267091.027743 | sda: h:b:t:l = 13:0:2:11
  267091.027872 | SCSI target 13:0:2 -> FC rport 13:0-6
  267091.027901 | sda: tgt_node_name = 0x5742b0f0000a0900
  267091.027908 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/state'
  267091.027931 | sda: path state = running
  267091.027943 | sda: 0 cyl, 64 heads, 32 sectors/track, start at 0
  267091.027948 | sda: vpd_vendor_id = 0 "undef" (setting: multipath internal)
  267091.027959 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/vpd_pg80'
  267091.027981 | sda: serial = 742b0f000000a0900000000000b2a87
  267091.028064 | sda: tur state = up
  267091.028072 | sda: uid = 36742b0f000000a0900000000000b2a87 (udev)
  267091.028077 | sda: detect_prio = yes (setting: multipath internal)
  267091.030190 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/access_state'
  267091.030216 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/preferred_path'
  267091.030234 | sda: prio = sysfs (setting: storage device autodetected)
  267091.030239 | sda: prio args = "" (setting: storage device autodetected)
  267091.030244 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/access_state'
  267091.030266 | open '/sys/devices/pci0000:80/0000:80:02.0/0000:82:00.1/host13/rport-13:0-6/target13:0:2/13:0:2:11/preferred_path'
  267091.030288 | sda: sysfs prio = 50
  san0 (36742b0f000000a0900000000000b2a87) dm-10 NFINIDAT,InfiniBox
  size=6.0T features='0' hwhandler='1 alua' wp=rw
  `-+- policy='round-robin 0' prio=50 status=active
    |- 11:0:0:11 sdb 8:16  active ready running
    |- 13:0:0:11 sde 8:64  active ready running
    |- 11:0:1:11 sdk 8:160 active ready running
    |- 13:0:1:11 sdi 8:128 active ready running
    |- 11:0:2:11 sdg 8:96  active ready running
    `- 13:0:2:11 sda 8:0   active ready running
  267091.034561 | tur checker refcount 6
  267091.034570 | sysfs prioritizer refcount 6
  267091.034611 | tur checker refcount 5
  267091.034619 | sysfs prioritizer refcount 5
  267091.034686 | tur checker refcount 4
  267091.034701 | sysfs prioritizer refcount 4
  267091.034760 | tur checker refcount 3
  267091.034777 | sysfs prioritizer refcount 3
  267091.034823 | tur checker refcount 2
  267091.034830 | sysfs prioritizer refcount 2
  267091.034877 | tur checker refcount 1
  267091.034889 | sysfs prioritizer refcount 1
  267091.035016 | unloading tur checker
  267091.035051 | unloading sysfs prioritizer
  267091.035075 | unloading const prioritizer

@bmarzins
Copy link
Collaborator

Martin was wondering if you could run this command
/usr/sbin/mpathpersist -o --preempt-abort --prout-type=5 --param-rk=1 --param-sark=2 -d /dev/mapper/san0
with -v4.

@bmarzins
Copy link
Collaborator

the output of
multipath -T
would also be helpful

@bmarzins
Copy link
Collaborator

Don't bother with the command outputs. I can reproduce this myself. It looks to be a legit mpathpersist bug. I'll work up a fix next week.

@ajaincdsc
Copy link
Author

ajaincdsc commented Jul 22, 2024

I know you said not to bother, but on the off chance it helps:

preempt-abort with -v4
860087.680085 | multipath-tools v0.9.4 (12/19, 2022)
860087.680112 | libdevmapper version 1.02.185
860087.680349 | kernel device mapper v4.47.0
860087.680370 | DM multipath kernel driver v1.14.0
860087.680811 | alias = san0
860087.680848 | 8:16: Empty device name
860087.680856 | 8:64: Empty device name
860087.680862 | 8:160: Empty device name
860087.680871 | 8:128: Empty device name
860087.680879 | 8:96: Empty device name
860087.680885 | 8:0: Empty device name
860087.681434 | sdb: size = 12884901888
860087.681696 | sdb: vendor = NFINIDAT
860087.681733 | sdb: product = InfiniBox
860087.681766 | sdb: rev = 0h
860087.682594 | sdb: h:b:t:l = 11:0:0:11
860087.683345 | sdb: tgt_node_name = 0x5742b0f0000a0900
860087.683360 | sdb: uid_attribute = ID_SERIAL (setting: multipath internal)
860087.683366 | sdb: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
860087.683766 | sdb: detect_checker = yes (setting: multipath internal)
860087.697364 | loading /lib/multipath/libchecktur.so checker
860087.697506 | checker tur: message table size = 3
860087.697516 | sdb: path_checker = tur (setting: storage device autodetected)
860087.697524 | sdb: checker timeout = 30 s (setting: kernel sysfs)
860087.697627 | sdb: tur state = up
860087.697643 | sdb: uid = 36742b0f000000a0900000000000b2a87 (udev)
860087.697650 | san0: adding new path sdb
860087.697943 | sde: size = 12884901888
860087.698198 | sde: vendor = NFINIDAT
860087.698231 | sde: product = InfiniBox
860087.698263 | sde: rev = 0h
860087.699101 | sde: h:b:t:l = 13:0:0:11
860087.699803 | sde: tgt_node_name = 0x5742b0f0000a0900
860087.699813 | sde: uid_attribute = ID_SERIAL (setting: multipath internal)
860087.699819 | sde: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
860087.700035 | sde: detect_checker = yes (setting: multipath internal)
860087.705050 | sde: path_checker = tur (setting: storage device autodetected)
860087.705062 | sde: checker timeout = 30 s (setting: kernel sysfs)
860087.705162 | sde: tur state = up
860087.705172 | sde: uid = 36742b0f000000a0900000000000b2a87 (udev)
860087.705178 | san0: adding new path sde
860087.705464 | sdk: size = 12884901888
860087.705716 | sdk: vendor = NFINIDAT
860087.705749 | sdk: product = InfiniBox
860087.705781 | sdk: rev = 0h
860087.706534 | sdk: h:b:t:l = 11:0:1:11
860087.707259 | sdk: tgt_node_name = 0x5742b0f0000a0900
860087.707268 | sdk: uid_attribute = ID_SERIAL (setting: multipath internal)
860087.707274 | sdk: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
860087.707485 | sdk: detect_checker = yes (setting: multipath internal)
860087.710272 | sdk: path_checker = tur (setting: storage device autodetected)
860087.710284 | sdk: checker timeout = 30 s (setting: kernel sysfs)
860087.710390 | sdk: tur state = up
860087.710399 | sdk: uid = 36742b0f000000a0900000000000b2a87 (udev)
860087.710406 | san0: adding new path sdk
860087.710687 | sdi: size = 12884901888
860087.710935 | sdi: vendor = NFINIDAT
860087.710969 | sdi: product = InfiniBox
860087.711007 | sdi: rev = 0h
860087.711740 | sdi: h:b:t:l = 13:0:1:11
860087.712471 | sdi: tgt_node_name = 0x5742b0f0000a0900
860087.712481 | sdi: uid_attribute = ID_SERIAL (setting: multipath internal)
860087.712487 | sdi: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
860087.712699 | sdi: detect_checker = yes (setting: multipath internal)
860087.715145 | sdi: path_checker = tur (setting: storage device autodetected)
860087.715157 | sdi: checker timeout = 30 s (setting: kernel sysfs)
860087.715260 | sdi: tur state = up
860087.715269 | sdi: uid = 36742b0f000000a0900000000000b2a87 (udev)
860087.715275 | san0: adding new path sdi
860087.715539 | sdg: size = 12884901888
860087.715788 | sdg: vendor = NFINIDAT
860087.715821 | sdg: product = InfiniBox
860087.715853 | sdg: rev = 0h
860087.716691 | sdg: h:b:t:l = 11:0:2:11
860087.717451 | sdg: tgt_node_name = 0x5742b0f0000a0900
860087.717462 | sdg: uid_attribute = ID_SERIAL (setting: multipath internal)
860087.717468 | sdg: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
860087.717674 | sdg: detect_checker = yes (setting: multipath internal)
860087.720092 | sdg: path_checker = tur (setting: storage device autodetected)
860087.720104 | sdg: checker timeout = 30 s (setting: kernel sysfs)
860087.720205 | sdg: tur state = up
860087.720215 | sdg: uid = 36742b0f000000a0900000000000b2a87 (udev)
860087.720222 | san0: adding new path sdg
860087.720488 | sda: size = 12884901888
860087.720730 | sda: vendor = NFINIDAT
860087.720764 | sda: product = InfiniBox
860087.720796 | sda: rev = 0h
860087.721596 | sda: h:b:t:l = 13:0:2:11
860087.722323 | sda: tgt_node_name = 0x5742b0f0000a0900
860087.722333 | sda: uid_attribute = ID_SERIAL (setting: multipath internal)
860087.722339 | sda: recheck_wwid = 1 (setting: multipath.conf defaults/devices section)
860087.722548 | sda: detect_checker = yes (setting: multipath internal)
860087.724976 | sda: path_checker = tur (setting: storage device autodetected)
860087.724987 | sda: checker timeout = 30 s (setting: kernel sysfs)
860087.725089 | sda: tur state = up
860087.725100 | sda: uid = 36742b0f000000a0900000000000b2a87 (udev)
860087.725107 | san0: adding new path sda
860087.725116 | san0: got hwe from path sdb
860087.725122 | san0: all_tg_pt = no (setting: multipath.conf defaults/devices section)
860087.725128 | san0: configured reservation key doesn't match: 0x0
PR out: command failed
multipath-tools v0.9.4 (12/19, 2022)
Usage: mpathpersist [OPTIONS] [DEVICE]
Options:
  --verbose|-v level         verbosity level
                 0           Critical messages
                 1           Error messages
                 2           Warning messages
                 3           Informational messages
                 4           Informational messages with trace enabled
  --clear|-C                 PR Out: Clear
  --device=DEVICE|-d DEVICE  query or change DEVICE
  --batch-file|-f FILE       run commands from FILE
  --help|-h                  output this usage message
  --hex|-H                   output response in hex
  --in|-i                    request PR In command 
  --out|-o                   request PR Out command
  --param-alltgpt|-Y         PR Out parameter 'ALL_TG_PT
  --param-aptpl|-Z           PR Out parameter 'APTPL'
  --read-keys|-k             PR In: Read Keys
  --param-rk=RK|-K RK        PR Out parameter reservation key
  --param-sark=SARK|-S SARK  PR Out parameter service action
                             reservation key (SARK is in hex)
  --preempt|-P               PR Out: Preempt
  --preempt-abort|-A         PR Out: Preempt and Abort
  --prout-type=TYPE|-T TYPE  PR Out command type
  --read-full-status|-s      PR In: Read Full Status
  --read-keys|-k             PR In: Read Keys
  --read-reservation|-r      PR In: Read Reservation
  --register|-G              PR Out: Register
  --register-ignore|-I       PR Out: Register and Ignore
  --release|-L               PR Out: Release
  --report-capabilities|-c   PR In: Report Capabilities
  --reserve|-R               PR Out: Reserve
  --transport-id=TIDS|-X TIDS  TransportIDs can be mentioned
                               in several forms
  --alloc-length=LEN|-l LEN  PR In: maximum allocation length
860087.725447 | unloading tur checker
multipath -T
defaults {
      verbosity 2
      polling_interval 5
      max_polling_interval 20
      reassign_maps "no"
      path_selector "service-time 0"
      path_grouping_policy "failover"
      uid_attribute "ID_SERIAL"
      prio "const"
      prio_args ""
      features "0"
      path_checker "tur"
      alias_prefix "mpath"
      failback "manual"
      rr_min_io 1000
      rr_min_io_rq 1
      max_fds "max"
      rr_weight "uniform"
      queue_without_daemon "no"
      allow_usb_devices "no"
      flush_on_last_del "no"
      user_friendly_names "no"
      fast_io_fail_tmo 5
      bindings_file "/etc/multipath/bindings"
      wwids_file "/etc/multipath/wwids"
      prkeys_file "/etc/multipath/prkeys"
      log_checker_err "always"
      all_tg_pt "no"
      retain_attached_hw_handler "yes"
      detect_prio "yes"
      detect_checker "yes"
      force_sync "yes"
      strict_timing "no"
      deferred_remove "no"
      delay_watch_checks "no"
      delay_wait_checks "no"
      san_path_err_threshold "no"
      san_path_err_forget_rate "no"
      san_path_err_recovery_time "no"
      marginal_path_err_sample_time "no"
      marginal_path_err_rate_threshold "no"
      marginal_path_err_recheck_gap_time "no"
      marginal_path_double_failed_time "no"
      find_multipaths "strict"
      uxsock_timeout 4000
      retrigger_tries 0
      retrigger_delay 10
      missing_uev_wait_timeout 30
      skip_kpartx "no"
      disable_changed_wwids "ignored"
      remove_retries 0
      ghost_delay "no"
      find_multipaths_timeout -10
      enable_foreign "NONE"
      marginal_pathgroups "off"
      recheck_wwid "no"
}
blacklist {
      devnode "^drbd[0-9]+"
      devnode "!^(sd[a-z]|dasd[a-z]|nvme[0-9])"
      protocol "scsi:unspec"
      protocol "scsi:spi"
      protocol "scsi:ssa"
      protocol "scsi:sbp"
      protocol "scsi:srp"
      protocol "scsi:iscsi"
      protocol "scsi:sas"
      protocol "scsi:adt"
      protocol "scsi:ata"
      protocol "ccw"
      protocol "cciss"
      protocol "nvme"
      protocol "undef"
      device {
              vendor "SGI"
              product "Universal Xport"
      }
      device {
              vendor "^DGC"
              product "LUNZ"
      }
      device {
              vendor "EMC"
              product "LUNZ"
      }
      device {
              vendor "DELL"
              product "Universal Xport"
      }
      device {
              vendor "FUJITSU"
              product "Universal Xport"
      }
      device {
              vendor "IBM"
              product "Universal Xport"
      }
      device {
              vendor "IBM"
              product "S/390"
      }
      device {
              vendor "LENOVO"
              product "Universal Xport"
      }
      device {
              vendor "(NETAPP|LSI|ENGENIO)"
              product "Universal Xport"
      }
      device {
              vendor "STK"
              product "Universal Xport"
      }
      device {
              vendor "SUN"
              product "Universal Xport"
      }
      device {
              vendor "(Intel|INTEL)"
              product "VTrak V-LUN"
      }
      device {
              vendor "Promise"
              product "VTrak V-LUN"
      }
      device {
              vendor "Promise"
              product "Vess V-LUN"
      }
}
blacklist_exceptions {
      property "(SCSI_IDENT_|ID_WWN)"
}
devices {
      device {
              vendor "NFINIDAT"
              product "InfiniBox"
              path_grouping_policy "group_by_prio"
              path_selector "round-robin 0"
              prio "alua"
              failback 30
              rr_weight "priorities"
              no_path_retry "fail"
              rr_min_io 1
              rr_min_io_rq 1
              flush_on_last_del "yes"
              fast_io_fail_tmo 15
              dev_loss_tmo 15
      }
}
overrides {
}
multipaths {
      multipath {
              wwid "36742b0f000000a0900000000000b2a87"
              alias "san0"
              reservation_key 0x2
              # alias "san0"
      }
}

@bmarzins
Copy link
Collaborator

Sent a fix for this to dm-devel.

https://lore.kernel.org/dm-devel/[email protected]/T/#u

@ajaincdsc
Copy link
Author

We built v0.9.4 and added this patch to the list of patches from debian's maintainers and are currently running it on 2x debian 12 hosts. The error looks to be resolved and so far no negative effects have reared their head (though its been only 2 days). Looks good overall, thank you!

I will leave this issue open for you to tie in to the next pull from the queue.

@mwilck
Copy link
Collaborator

mwilck commented Aug 6, 2024

Thanks a lot, @bmarzins!

It's strange that this hasn't caused issues earlier, the affected code is ancient.

@mwilck
Copy link
Collaborator

mwilck commented Aug 6, 2024

@bmarzins, I don't quite understand your explanation yet why we haven't seen this error before. "This appeared to work for many commands because of an error in checking if the correct reservation key was used." The code that compared mpp->reservation_key against the command parameters has been in place for a long time, and it should always have had the result observed by @ajaincdsc, unless either key or sa_key happened to be 0.

Is it just that? Many of the "simple" PROUT commands just need either RK or SARK, and other commands would always have failed, but nobody noticed?

@bmarzins
Copy link
Collaborator

bmarzins commented Aug 7, 2024

Yep. The following commands wouldn't fail before, because the command doesn't set both the RK and SARK.

registering a key:
mpathpersist -o -G -S <sark> <devpath>

reserving a device:
mpathpersist -o -R -K <rk> -T <type_nr> <devpath>

clearing a reservation:
mpathpersist -o -L -K <rk> -T <type_nr> <devpath>

clearing a registration:
mpathpersist -o -G -K <rk> <devpath>

As long as nothing goes wrong, those may well be the only commands that ever get run. But doing something like preempting and aborting an existing reservation fails, since it requires both a RK (which should match mpp->reservation_key) and a SARK:
mpathpersist -o -A -T <type_nr> -K <rk> -S <sark> <devpath>

Also, this only fails for configurations where the key is set in the multipaths section. It works fine if reservation_key is set to "file" in the defaults section, which is much easier to configure, and what, I assume, most people are using.

@mwilck
Copy link
Collaborator

mwilck commented Aug 7, 2024

Right ... "file" would be set in the defaults section, not the multipaths section. That was the part I'd been missing. Thanks.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging a pull request may close this issue.

3 participants