opensvc / multipath-tools

Other
57 stars 46 forks source link

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

Closed ajaincdsc closed 3 days ago

ajaincdsc commented 1 month ago

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 commented 1 month ago

Check your reservation_key setting in multipath.conf.

ajaincdsc commented 1 month ago
multipaths {
        multipath {
                wwid "----"
                alias san0
                reservation_key 0x2
        }
}

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

ajaincdsc commented 1 month ago

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 commented 1 month ago

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 commented 1 month ago
:~ % 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 commented 1 month ago

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 commented 1 month ago

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 commented 1 month ago

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 commented 1 month ago

the output of multipath -T would also be helpful

bmarzins commented 1 month ago

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 commented 1 month ago

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 commented 1 month ago

Sent a fix for this to dm-devel.

https://lore.kernel.org/dm-devel/20240722233616.2496277-1-bmarzins@redhat.com/T/#u

ajaincdsc commented 1 month ago

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 commented 3 weeks ago

Thanks a lot, @bmarzins!

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

mwilck commented 3 weeks ago

@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 commented 3 weeks ago

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 commented 3 weeks ago

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