[dm-devel] create lvm2 snapshot will take a long time while large IO on origin lv(till large IO ends)

Busby chaimvy at gmail.com
Thu Apr 29 03:24:08 UTC 2010


 Hi All,
      I create vg00 using /dev/sd[b-e] four disks. Then I create  lv00 as
origin lv and use 'dd if=/dev/zero of=/dev/vg00/lv00 bs=1M' cmd to make big
IO on lv00. When the dd is copying data, I use 'lvcreate -L 10G -s
/dev/vg00/lv00 -n lv_snap1 -vvvv' to create a snapshot lv, the command
suspend, while this cmd is suspending, the 'vgs' or 'lvs' will also be
suspended.
     I get the '-vvvv' debug message, the last 3 lines of the lvcreate snap
's suspending message are:
#ioctl/libdm-iface.c:1782         dm info   (254:0) NF   [16384]
#libdm-deptree.c:986     Suspending vg00-lv00 (254:0) with filesystem sync
with device flush
#ioctl/libdm-iface.c:1782         dm suspend   (254:0) NF   [16384]

the 'vgs -vvvv' cmd  last 3 lines message are :

#locking/file_locking.c:141         _do_flock /var/lock/lvm/V_vg00:aux WB
#locking/file_locking.c:51         _undo_flock /var/lock/lvm/V_vg00:aux
#locking/file_locking.c:141         _do_flock /var/lock/lvm/V_vg00 RB

it seems the vgs suspend because the lvcreate cmd don't unlock the flock,
but why the lvcreating of snapshot lv will be suspended while the origin is
on big data IO?

    The output of the '-vvvv' till the suspend are as attachments of this
email. 'lvcreate_snap_suspend.txt' is the debug messages of the lvcreate and
the lvcreate_snap_vgs_suspend.txt' is the 'vgs -vvvv' while the snap lv
creating.

    Thank you very much.

Best Regards,
Busby
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/dm-devel/attachments/20100429/14455d9f/attachment.htm>
-------------- next part --------------
[root at new-host ~]# lvcreate -L 10G -s /dev/vg00/lv00 -n lv_snap1 -vvvv
#lvmcmdline.c:1049         Processing: lvcreate -L 10G -s /dev/vg00/lv00 -n lv_snap1 -vvvv
#lvmcmdline.c:1052         O_DIRECT will be used
#config/config.c:987       Setting global/locking_type to 1
#config/config.c:992       global/wait_for_locks not found in config: defaulting to 1
#locking/locking.c:242       File-based locking selected.
#config/config.c:964       Setting global/locking_dir to /var/lock/lvm
#lvcreate.c:373     Setting chunksize to 8 sectors.
#activate/activate.c:361       Getting target version for snapshot
#ioctl/libdm-iface.c:1782         dm version   OF   [16384]
#ioctl/libdm-iface.c:1782         dm versions   OF   [16384]
#activate/activate.c:361       Getting target version for snapshot-origin
#ioctl/libdm-iface.c:1782         dm versions   OF   [16384]
#lvcreate.c:291     Setting logging type to disk
#config/config.c:987       Setting activation/mirror_region_size to 512
#lvcreate.c:523     Finding volume group "vg00"
#locking/file_locking.c:235       Locking /var/lock/lvm/V_vg00 WB
#locking/file_locking.c:141         _do_flock /var/lock/lvm/V_vg00:aux WB
#locking/file_locking.c:141         _do_flock /var/lock/lvm/V_vg00 WB
#locking/file_locking.c:51         _undo_flock /var/lock/lvm/V_vg00:aux
#device/dev-io.c:487         Opened /dev/ramdisk RW O_DIRECT
#device/dev-io.c:134         /dev/ramdisk: block size is 4096 bytes
#label/label.c:184       /dev/ramdisk: No label detected
#device/dev-io.c:533         Closed /dev/ramdisk
#device/dev-io.c:487         Opened /dev/ram RW O_DIRECT
#device/dev-io.c:134         /dev/ram: block size is 4096 bytes
#label/label.c:184       /dev/ram: No label detected
#device/dev-io.c:533         Closed /dev/ram
#device/dev-io.c:487         Opened /dev/sda1 RW O_DIRECT
#device/dev-io.c:134         /dev/sda1: block size is 1024 bytes
#label/label.c:184       /dev/sda1: No label detected
#device/dev-io.c:533         Closed /dev/sda1
#device/dev-io.c:487         Opened /dev/ram2 RW O_DIRECT
#device/dev-io.c:134         /dev/ram2: block size is 4096 bytes
#label/label.c:184       /dev/ram2: No label detected
#device/dev-io.c:533         Closed /dev/ram2
#device/dev-io.c:487         Opened /dev/root RW O_DIRECT
#device/dev-io.c:134         /dev/root: block size is 4096 bytes
#label/label.c:184       /dev/root: No label detected
#device/dev-io.c:533         Closed /dev/root
#device/dev-io.c:487         Opened /dev/ram3 RW O_DIRECT
#device/dev-io.c:134         /dev/ram3: block size is 4096 bytes
#label/label.c:184       /dev/ram3: No label detected
#device/dev-io.c:533         Closed /dev/ram3
#device/dev-io.c:487         Opened /dev/sda3 RW O_DIRECT
#device/dev-io.c:134         /dev/sda3: block size is 4096 bytes
#label/label.c:184       /dev/sda3: No label detected
#device/dev-io.c:533         Closed /dev/sda3
#device/dev-io.c:487         Opened /dev/ram4 RW O_DIRECT
#device/dev-io.c:134         /dev/ram4: block size is 4096 bytes
#label/label.c:184       /dev/ram4: No label detected
#device/dev-io.c:533         Closed /dev/ram4
#device/dev-io.c:487         Opened /dev/ram5 RW O_DIRECT
#device/dev-io.c:134         /dev/ram5: block size is 4096 bytes
#label/label.c:184       /dev/ram5: No label detected
#device/dev-io.c:533         Closed /dev/ram5
#device/dev-io.c:487         Opened /dev/sda5 RW O_DIRECT
#device/dev-io.c:134         /dev/sda5: block size is 1024 bytes
#label/label.c:184       /dev/sda5: No label detected
#device/dev-io.c:533         Closed /dev/sda5
#device/dev-io.c:487         Opened /dev/ram6 RW O_DIRECT
#device/dev-io.c:134         /dev/ram6: block size is 4096 bytes
#label/label.c:184       /dev/ram6: No label detected
#device/dev-io.c:533         Closed /dev/ram6
#device/dev-io.c:487         Opened /dev/ram7 RW O_DIRECT
#device/dev-io.c:134         /dev/ram7: block size is 4096 bytes
#label/label.c:184       /dev/ram7: No label detected
#device/dev-io.c:533         Closed /dev/ram7
#device/dev-io.c:487         Opened /dev/ram8 RW O_DIRECT
#device/dev-io.c:134         /dev/ram8: block size is 4096 bytes
#label/label.c:184       /dev/ram8: No label detected
#device/dev-io.c:533         Closed /dev/ram8
#device/dev-io.c:487         Opened /dev/ram9 RW O_DIRECT
#device/dev-io.c:134         /dev/ram9: block size is 4096 bytes
#label/label.c:184       /dev/ram9: No label detected
#device/dev-io.c:533         Closed /dev/ram9
#device/dev-io.c:487         Opened /dev/ram10 RW O_DIRECT
#device/dev-io.c:134         /dev/ram10: block size is 4096 bytes
#label/label.c:184       /dev/ram10: No label detected
#device/dev-io.c:533         Closed /dev/ram10
#device/dev-io.c:487         Opened /dev/ram11 RW O_DIRECT
#device/dev-io.c:134         /dev/ram11: block size is 4096 bytes
#label/label.c:184       /dev/ram11: No label detected
#device/dev-io.c:533         Closed /dev/ram11
#device/dev-io.c:487         Opened /dev/ram12 RW O_DIRECT
#device/dev-io.c:134         /dev/ram12: block size is 4096 bytes
#label/label.c:184       /dev/ram12: No label detected
#device/dev-io.c:533         Closed /dev/ram12
#device/dev-io.c:487         Opened /dev/ram13 RW O_DIRECT
#device/dev-io.c:134         /dev/ram13: block size is 4096 bytes
#label/label.c:184       /dev/ram13: No label detected
#device/dev-io.c:533         Closed /dev/ram13
#device/dev-io.c:487         Opened /dev/ram14 RW O_DIRECT
#device/dev-io.c:134         /dev/ram14: block size is 4096 bytes
#label/label.c:184       /dev/ram14: No label detected
#device/dev-io.c:533         Closed /dev/ram14
#device/dev-io.c:487         Opened /dev/ram15 RW O_DIRECT
#device/dev-io.c:134         /dev/ram15: block size is 4096 bytes
#label/label.c:184       /dev/ram15: No label detected
#device/dev-io.c:533         Closed /dev/ram15
#device/dev-io.c:487         Opened /dev/sdb RW O_DIRECT
#device/dev-io.c:134         /dev/sdb: block size is 4096 bytes
#label/label.c:160       /dev/sdb: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sdb: Found metadata at 9728 size 1900 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG vg00 with 1 mdas
#cache/lvmcache.c:873         lvmcache: /dev/sdb: setting vg00 VGID to t1AHib0Nn5foWd16imXIJYi137IRLQ0v
#cache/lvmcache.c:1123         lvmcache: /dev/sdb: VG vg00: Set creation host to new-host.
#device/dev-io.c:487         Opened /dev/sdc RW O_DIRECT
#device/dev-io.c:134         /dev/sdc: block size is 4096 bytes
#label/label.c:160       /dev/sdc: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sdc: Found metadata at 9728 size 1900 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#device/dev-io.c:487         Opened /dev/sdd RW O_DIRECT
#device/dev-io.c:134         /dev/sdd: block size is 4096 bytes
#label/label.c:160       /dev/sdd: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sdd: Found metadata at 9728 size 1900 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#device/dev-io.c:487         Opened /dev/sde RW O_DIRECT
#device/dev-io.c:134         /dev/sde: block size is 4096 bytes
#label/label.c:160       /dev/sde: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sde: Found metadata at 9728 size 1900 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (4) from /dev/sdb at 9728 size 1900
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (4) from /dev/sdc at 9728 size 1900
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (4) from /dev/sdd at 9728 size 1900
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (4) from /dev/sde at 9728 size 1900
#metadata/pv_manip.c:296         /dev/sdb 0:      0  25600: lv00(0:0)
#metadata/pv_manip.c:296         /dev/sdb 1:  25600   2560: lv_snap(0:0)
#metadata/pv_manip.c:296         /dev/sdb 2:  28160 210307: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sdc 0:      0 238467: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sdd 0:      0 238467: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sde 0:      0 238467: NULL(0:0)
#activate/dev_manager.c:219         Getting device info for vg00-lv00 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA NF   [16384]
#format_text/archiver.c:127     Archiving volume group "vg00" metadata (seqno 4).
#metadata/lv_manip.c:2042     Creating logical volume lv_snap1
#metadata/pv_map.c:55         Allowing allocation on /dev/sdb start PE 28160 length 210307
#metadata/pv_map.c:55         Allowing allocation on /dev/sdc start PE 0 length 238467
#metadata/pv_map.c:55         Allowing allocation on /dev/sdd start PE 0 length 238467
#metadata/pv_map.c:55         Allowing allocation on /dev/sde start PE 0 length 238467
#metadata/lv_manip.c:1366         Trying allocation using contiguous policy.  Need 2560 extents for 1 parallel areas and 0 log areas of 0 extents. (Total 2560 extents.)
#metadata/lv_manip.c:1230         Trying allocation area 0 on /dev/sdb start PE 28160 length 2560 leaving 207747.
#metadata/lv_manip.c:1230         Trying allocation area 1 on /dev/sdc start PE 0 length 2560 leaving 235907.
#metadata/lv_manip.c:1230         Trying allocation area 2 on /dev/sdd start PE 0 length 2560 leaving 235907.
#metadata/lv_manip.c:1230         Trying allocation area 3 on /dev/sde start PE 0 length 2560 leaving 235907.
#metadata/lv_manip.c:795         Allocating parallel area 0 on /dev/sdb start PE 28160 length 2560.
#metadata/pv_manip.c:296         /dev/sdb 0:      0  25600: lv00(0:0)
#metadata/pv_manip.c:296         /dev/sdb 1:  25600   2560: lv_snap(0:0)
#metadata/pv_manip.c:296         /dev/sdb 2:  28160   2560: lv_snap1(0:0)
#metadata/pv_manip.c:296         /dev/sdb 3:  30720 207747: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sdc 0:      0 238467: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sdd 0:      0 238467: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sde 0:      0 238467: NULL(0:0)
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sdb at 11776 len 2150
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sdc at 11776 len 2150
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sdd at 11776 len 2150
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sde at 11776 len 2150
#format_text/format-text.c:695         Pre-Committing vg00 metadata (5) to /dev/sdb header at 4096
#format_text/format-text.c:695         Pre-Committing vg00 metadata (5) to /dev/sdc header at 4096
#format_text/format-text.c:695         Pre-Committing vg00 metadata (5) to /dev/sdd header at 4096
#format_text/format-text.c:695         Pre-Committing vg00 metadata (5) to /dev/sde header at 4096
#format_text/format-text.c:695         Committing vg00 metadata (5) to /dev/sdb header at 4096
#format_text/format-text.c:695         Committing vg00 metadata (5) to /dev/sdc header at 4096
#format_text/format-text.c:695         Committing vg00 metadata (5) to /dev/sdd header at 4096
#format_text/format-text.c:695         Committing vg00 metadata (5) to /dev/sde header at 4096
#format_text/archiver.c:390     Creating volume group backup "/etc/lvm/backup/vg00" (seqno 5).
#format_text/format-text.c:888         Writing vg00 metadata to /etc/lvm/backup/.lvm_new-host_7012_1661332237
#format_text/format-text.c:932         Committing vg00 metadata (5)
#format_text/format-text.c:933         Renaming /etc/lvm/backup/vg00.tmp to /etc/lvm/backup/vg00
#locking/file_locking.c:304       Locking LV t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB (EX)
#metadata/metadata.c:3073       Finding volume group for uuid t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB
#label/label.c:160       /dev/sdb: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sdb: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:160       /dev/sdc: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sdc: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:160       /dev/sdd: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sdd: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:160       /dev/sde: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sde: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sdb at 11776 size 2150
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sdc at 11776 size 2150
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sdd at 11776 size 2150
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sde at 11776 size 2150
#metadata/metadata.c:3079     Found volume group "vg00"
#activate/dev_manager.c:219         Getting device info for vg00-lv_snap1 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB NF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB NF   [16384]
#device/dev-io.c:291       /dev/sdb: read_ahead is 256 sectors
#mm/memlock.c:265       Locking memory
#mm/memlock.c:176         mlock        648KiB       400000 -       4a2000 r-xp  00000000 08:02 3965044                            /sbin/lvm
#mm/memlock.c:176         mlock         28KiB       6a2000 -       6a9000 rw-p  000a2000 08:02 3965044                            /sbin/lvm
#mm/memlock.c:176         mlock      10508KiB       6a9000 -      10ec000 rw-p  006a9000 00:00 0                                  [heap]
#mm/memlock.c:176         mlock        112KiB   332f800000 -   332f81c000 r-xp  00000000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         mlock          4KiB   332fa1b000 -   332fa1c000 r--p  0001b000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         mlock          4KiB   332fa1c000 -   332fa1d000 rw-p  0001c000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         mlock       1332KiB   332fc00000 -   332fd4d000 r-xp  00000000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:140         mlock area unreadable '332fd4d000-332ff4d000 ---p 0014d000 08:02 2949449                        /lib64/libc-2.5.so': Skipping.
#mm/memlock.c:176         mlock         16KiB   332ff4d000 -   332ff51000 r--p  0014d000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:176         mlock          4KiB   332ff51000 -   332ff52000 rw-p  00151000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:176         mlock         20KiB   332ff52000 -   332ff57000 rw-p  332ff52000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330400000-3330402000 r-xp 00000000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:140         mlock area unreadable '3330402000-3330602000 ---p 00002000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330602000-3330603000 r--p 00002000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330603000-3330604000 rw-p 00003000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:176         mlock        236KiB   3331400000 -   333143b000 r-xp  00000000 08:02 2949463                        /lib64/libsepol.so.1
#mm/memlock.c:140         mlock area unreadable '333143b000-333163b000 ---p 0003b000 08:02 2949463                        /lib64/libsepol.so.1': Skipping.
#mm/memlock.c:176         mlock          4KiB   333163b000 -   333163c000 rw-p  0003b000 08:02 2949463                        /lib64/libsepol.so.1
#mm/memlock.c:176         mlock         40KiB   333163c000 -   3331646000 rw-p  333163c000 00:00 0 
#mm/memlock.c:176         mlock         84KiB   3331800000 -   3331815000 r-xp  00000000 08:02 2949464                        /lib64/libselinux.so.1
#mm/memlock.c:140         mlock area unreadable '3331815000-3331a15000 ---p 00015000 08:02 2949464                        /lib64/libselinux.so.1': Skipping.
#mm/memlock.c:176         mlock          8KiB   3331a15000 -   3331a17000 rw-p  00015000 08:02 2949464                        /lib64/libselinux.so.1
#mm/memlock.c:176         mlock          4KiB   3331a17000 -   3331a18000 rw-p  3331a17000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libreadline.so.' matches '3331c00000-3331c35000 r-xp 00000000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:140         mlock area unreadable '3331c35000-3331e34000 ---p 00035000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:158         mlock default filter '/libreadline.so.' matches '3331e34000-3331e3c000 rw-p 00034000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:176         mlock          4KiB   3331e3c000 -   3331e3d000 rw-p  3331e3c000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libncurses.so.' matches '3342600000-334264f000 r-xp 00000000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:140         mlock area unreadable '334264f000-334284e000 ---p 0004f000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:158         mlock default filter '/libncurses.so.' matches '334284e000-334285c000 rw-p 0004e000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:176         mlock          4KiB   334285c000 -   334285d000 rw-p  334285c000 00:00 0 
#mm/memlock.c:158         mlock default filter 'locale/locale-archive' matches '7f05d02fc000-7f05d38d4000 r--p 00000000 08:02 4034433                    /usr/lib/locale/locale-archive': Skipping.
#mm/memlock.c:176         mlock         16KiB 7f05d38d4000 - 7f05d38d8000 rw-p  7f05d38d4000 00:00 0 
#mm/memlock.c:176         mlock        112KiB 7f05d38d8000 - 7f05d38f4000 r-xp  00000000 08:02 5668894                    /lib/libdevmapper.so.1.02
#mm/memlock.c:140         mlock area unreadable '7f05d38f4000-7f05d3af4000 ---p 0001c000 08:02 5668894                    /lib/libdevmapper.so.1.02': Skipping.
#mm/memlock.c:176         mlock          8KiB 7f05d3af4000 - 7f05d3af6000 rw-p  0001c000 08:02 5668894                    /lib/libdevmapper.so.1.02
#mm/memlock.c:176         mlock          4KiB 7f05d3b07000 - 7f05d3b08000 rw-p  7f05d3b07000 00:00 0 
#mm/memlock.c:158         mlock default filter 'gconv/gconv-modules.cache' matches '7f05d3b08000-7f05d3b0f000 r--s 00000000 08:02 4161798                    /usr/lib64/gconv/gconv-modules.cache': Skipping.
#mm/memlock.c:176         mlock          8KiB 7f05d3b0f000 - 7f05d3b11000 rw-p  7f05d3b0f000 00:00 0 
#mm/memlock.c:176         mlock        276KiB 7fffdbacc000 - 7fffdbb11000 rw-p  7ffffffba000 00:00 0                      [stack]
#mm/memlock.c:148         mlock ignore filter '[vdso]' matches '7fffdbbff000-7fffdbc00000 r-xp 7fffdbbff000 00:00 0                      [vdso]': Skipping.
#mm/memlock.c:148         mlock ignore filter '[vsyscall]' matches 'ffffffffff600000-ffffffffff601000 r-xp 00000000 00:00 0                  [vsyscall]': Skipping.
#mm/memlock.c:232         Locked 13807616 bytes
#mm/memlock.c:318         memlock_count inc to 1
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1-real [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1-cow [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow OF   [16384]
#activate/dev_manager.c:1089         Checking kernel supports striped segment type for lv_snap1
#activate/activate.c:361       Getting target version for linear
#ioctl/libdm-iface.c:1782         dm versions   OF   [16384]
#activate/activate.c:361       Getting target version for striped
#ioctl/libdm-iface.c:1782         dm versions   OF   [16384]
#metadata/metadata.c:2142         Calculated readahead of LV lv_snap1 is 256
#libdm-deptree.c:1268     Creating vg00-lv_snap1
#ioctl/libdm-iface.c:1782         dm create vg00-lv_snap1 LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB NF   [16384]
#libdm-common.c:771         vg00-lv_snap1: Stacking NODE_ADD (254,4) 0:0 0600
#libdm-deptree.c:1601     Loading vg00-lv_snap1 table (254:4)
#libdm-deptree.c:1547         Adding target to (254:4): 0 20971520 linear 8:16 230687104
#ioctl/libdm-iface.c:1782         dm table   (254:4) OF   [16384]
#ioctl/libdm-iface.c:1782         dm reload   (254:4) NF   [16384]
#libdm-deptree.c:1641         Table size changed from 0 to 20971520 for vg00-lv_snap1
#libdm-deptree.c:941     Resuming vg00-lv_snap1 (254:4)
#ioctl/libdm-iface.c:1782         dm resume   (254:4) NF   [16384]
#libdm-common.c:800         vg00-lv_snap1: Stacking NODE_READ_AHEAD 256 (flags=1)
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:4) OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1-real [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1-cow [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow OF   [16384]
#mm/memlock.c:282       Unlocking memory
#mm/memlock.c:176         munlock        648KiB       400000 -       4a2000 r-xp  00000000 08:02 3965044                            /sbin/lvm
#mm/memlock.c:176         munlock         28KiB       6a2000 -       6a9000 rw-p  000a2000 08:02 3965044                            /sbin/lvm
#mm/memlock.c:176         munlock      10508KiB       6a9000 -      10ec000 rw-p  006a9000 00:00 0                                  [heap]
#mm/memlock.c:176         munlock        112KiB   332f800000 -   332f81c000 r-xp  00000000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         munlock          4KiB   332fa1b000 -   332fa1c000 r--p  0001b000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         munlock          4KiB   332fa1c000 -   332fa1d000 rw-p  0001c000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         munlock       1332KiB   332fc00000 -   332fd4d000 r-xp  00000000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:140         mlock area unreadable '332fd4d000-332ff4d000 ---p 0014d000 08:02 2949449                        /lib64/libc-2.5.so': Skipping.
#mm/memlock.c:176         munlock         16KiB   332ff4d000 -   332ff51000 r--p  0014d000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:176         munlock          4KiB   332ff51000 -   332ff52000 rw-p  00151000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:176         munlock         20KiB   332ff52000 -   332ff57000 rw-p  332ff52000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330400000-3330402000 r-xp 00000000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:140         mlock area unreadable '3330402000-3330602000 ---p 00002000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330602000-3330603000 r--p 00002000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330603000-3330604000 rw-p 00003000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:176         munlock        236KiB   3331400000 -   333143b000 r-xp  00000000 08:02 2949463                        /lib64/libsepol.so.1
#mm/memlock.c:140         mlock area unreadable '333143b000-333163b000 ---p 0003b000 08:02 2949463                        /lib64/libsepol.so.1': Skipping.
#mm/memlock.c:176         munlock          4KiB   333163b000 -   333163c000 rw-p  0003b000 08:02 2949463                        /lib64/libsepol.so.1
#mm/memlock.c:176         munlock         40KiB   333163c000 -   3331646000 rw-p  333163c000 00:00 0 
#mm/memlock.c:176         munlock         84KiB   3331800000 -   3331815000 r-xp  00000000 08:02 2949464                        /lib64/libselinux.so.1
#mm/memlock.c:140         mlock area unreadable '3331815000-3331a15000 ---p 00015000 08:02 2949464                        /lib64/libselinux.so.1': Skipping.
#mm/memlock.c:176         munlock          8KiB   3331a15000 -   3331a17000 rw-p  00015000 08:02 2949464                        /lib64/libselinux.so.1
#mm/memlock.c:176         munlock          4KiB   3331a17000 -   3331a18000 rw-p  3331a17000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libreadline.so.' matches '3331c00000-3331c35000 r-xp 00000000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:140         mlock area unreadable '3331c35000-3331e34000 ---p 00035000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:158         mlock default filter '/libreadline.so.' matches '3331e34000-3331e3c000 rw-p 00034000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:176         munlock          4KiB   3331e3c000 -   3331e3d000 rw-p  3331e3c000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libncurses.so.' matches '3342600000-334264f000 r-xp 00000000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:140         mlock area unreadable '334264f000-334284e000 ---p 0004f000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:158         mlock default filter '/libncurses.so.' matches '334284e000-334285c000 rw-p 0004e000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:176         munlock          4KiB   334285c000 -   334285d000 rw-p  334285c000 00:00 0 
#mm/memlock.c:158         mlock default filter 'locale/locale-archive' matches '7f05d02fc000-7f05d38d4000 r--p 00000000 08:02 4034433                    /usr/lib/locale/locale-archive': Skipping.
#mm/memlock.c:176         munlock         16KiB 7f05d38d4000 - 7f05d38d8000 rw-p  7f05d38d4000 00:00 0 
#mm/memlock.c:176         munlock        112KiB 7f05d38d8000 - 7f05d38f4000 r-xp  00000000 08:02 5668894                    /lib/libdevmapper.so.1.02
#mm/memlock.c:140         mlock area unreadable '7f05d38f4000-7f05d3af4000 ---p 0001c000 08:02 5668894                    /lib/libdevmapper.so.1.02': Skipping.
#mm/memlock.c:176         munlock          8KiB 7f05d3af4000 - 7f05d3af6000 rw-p  0001c000 08:02 5668894                    /lib/libdevmapper.so.1.02
#mm/memlock.c:176         munlock          4KiB 7f05d3b07000 - 7f05d3b08000 rw-p  7f05d3b07000 00:00 0 
#mm/memlock.c:158         mlock default filter 'gconv/gconv-modules.cache' matches '7f05d3b08000-7f05d3b0f000 r--s 00000000 08:02 4161798                    /usr/lib64/gconv/gconv-modules.cache': Skipping.
#mm/memlock.c:176         munlock          8KiB 7f05d3b0f000 - 7f05d3b11000 rw-p  7f05d3b0f000 00:00 0 
#mm/memlock.c:176         munlock        276KiB 7fffdbacc000 - 7fffdbb11000 rw-p  7ffffffba000 00:00 0                      [stack]
#mm/memlock.c:148         mlock ignore filter '[vdso]' matches '7fffdbbff000-7fffdbc00000 r-xp 7fffdbbff000 00:00 0                      [vdso]': Skipping.
#mm/memlock.c:148         mlock ignore filter '[vsyscall]' matches 'ffffffffff600000-ffffffffff601000 r-xp 00000000 00:00 0                  [vsyscall]': Skipping.
#mm/memlock.c:232         Unlocked 13807616 bytes
#mm/memlock.c:327         memlock_count dec to 0
#libdm-common.c:448         Created /dev/mapper/vg00-lv_snap1
#libdm-common.c:391         Setting SELinux context for /dev/mapper/vg00-lv_snap1 to system_u:object_r:fixed_disk_device_t.
#libdm-common.c:573         vg00-lv_snap1: read ahead is 256
#libdm-common.c:596         vg00-lv_snap1: Setting read ahead to 256
#activate/fs.c:198       Linking /dev/vg00/lv_snap1 -> /dev/mapper/vg00-lv_snap1
#libdm-common.c:391         Setting SELinux context for /dev/vg00/lv_snap1 to system_u:object_r:device_t.
#metadata/lv_manip.c:2918     Clearing start of logical volume "lv_snap1"
#device/dev-cache.c:262         /dev/vg00/lv_snap1: Added to device cache
#device/dev-io.c:487         Opened /dev/vg00/lv_snap1 RW O_DIRECT
#device/dev-io.c:692         Wiping /dev/vg00/lv_snap1 at sector 0 length 8 sectors
#device/dev-io.c:134         /dev/vg00/lv_snap1: block size is 4096 bytes
#device/dev-io.c:533         Closed /dev/vg00/lv_snap1
#metadata/lv_manip.c:2042     Creating logical volume snapshot1
#metadata/lv_manip.c:2198         LV lv_snap1 in VG vg00 is now hidden.
#metadata/pv_manip.c:296         /dev/sdb 0:      0  25600: lv00(0:0)
#metadata/pv_manip.c:296         /dev/sdb 1:  25600   2560: lv_snap(0:0)
#metadata/pv_manip.c:296         /dev/sdb 2:  28160   2560: lv_snap1(0:0)
#metadata/pv_manip.c:296         /dev/sdb 3:  30720 207747: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sdc 0:      0 238467: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sdd 0:      0 238467: NULL(0:0)
#metadata/pv_manip.c:296         /dev/sde 0:      0 238467: NULL(0:0)
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sdb at 14336 len 2392
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sdc at 14336 len 2392
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sdd at 14336 len 2392
#format_text/format-text.c:590         Writing vg00 metadata to /dev/sde at 14336 len 2392
#format_text/format-text.c:695         Pre-Committing vg00 metadata (6) to /dev/sdb header at 4096
#format_text/format-text.c:695         Pre-Committing vg00 metadata (6) to /dev/sdc header at 4096
#format_text/format-text.c:695         Pre-Committing vg00 metadata (6) to /dev/sdd header at 4096
#format_text/format-text.c:695         Pre-Committing vg00 metadata (6) to /dev/sde header at 4096
#locking/file_locking.c:299       Locking LV t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA (W)
#metadata/metadata.c:3073       Finding volume group for uuid t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA
#label/label.c:160       /dev/sdb: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sdb: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:160       /dev/sdc: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sdc: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:160       /dev/sdd: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sdd: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:160       /dev/sde: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG #orphans_lvm2 (#orphans_lvm2) with 1 mdas
#format_text/format-text.c:1137         /dev/sde: Found metadata at 11776 size 2150 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sdb at 11776 size 2150
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sdc at 11776 size 2150
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sdd at 11776 size 2150
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 metadata (5) from /dev/sde at 11776 size 2150
#metadata/metadata.c:3079     Found volume group "vg00"
#metadata/metadata.c:3073       Finding volume group for uuid t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 pre-commit metadata (6) from /dev/sdb at 14336 size 2392
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 pre-commit metadata (6) from /dev/sdc at 14336 size 2392
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 pre-commit metadata (6) from /dev/sdd at 14336 size 2392
#label/label.c:270         Using cached label for /dev/sdb
#label/label.c:270         Using cached label for /dev/sdc
#label/label.c:270         Using cached label for /dev/sdd
#label/label.c:270         Using cached label for /dev/sde
#format_text/format-text.c:498         Read vg00 pre-commit metadata (6) from /dev/sde at 14336 size 2392
#metadata/metadata.c:3079     Found volume group "vg00"
#activate/dev_manager.c:219         Getting device info for vg00-lv00 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA NF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv00 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:0) OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:2) OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv00-real [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-real]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-real OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv00-cow [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-cow]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-cow OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-cow OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0 OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:1) OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:3) OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap-real [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-real]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-real OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-real OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap-cow [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-cow]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-cow OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:4) OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1-real [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-real OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap1-cow [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow OF   [16384]
#activate/dev_manager.c:1089         Checking kernel supports striped segment type for lv00
#activate/dev_manager.c:1089         Checking kernel supports striped segment type for lv00-real
#metadata/metadata.c:2142         Calculated readahead of LV lv00 is 256
#activate/dev_manager.c:1089         Checking kernel supports snapshot segment type for lv_snap
#activate/dev_manager.c:1089         Checking kernel supports snapshot segment type for lv_snap-cow
#metadata/metadata.c:2142         Calculated readahead of LV lv_snap is 256
#metadata/metadata.c:2142         Calculated readahead of LV lv_snap is 256
#activate/dev_manager.c:1089         Checking kernel supports snapshot segment type for lv_snap1
#activate/dev_manager.c:1089         Checking kernel supports snapshot segment type for lv_snap1-cow
#metadata/metadata.c:2142         Calculated readahead of LV lv_snap1 is 256
#metadata/metadata.c:2142         Calculated readahead of LV lv_snap1 is 256
#metadata/metadata.c:2142         Calculated readahead of LV lv00 is 256
#libdm-deptree.c:1601     Loading vg00-lv00-real table (254:2)
#libdm-deptree.c:1547         Adding target to (254:2): 0 209715200 linear 8:16 384
#ioctl/libdm-iface.c:1782         dm table   (254:2) OF   [16384]
#libdm-deptree.c:1634     Suppressed vg00-lv00-real identical table reload.
#libdm-deptree.c:1601     Loading vg00-lv00 table (254:0)
#libdm-deptree.c:1547         Adding target to (254:0): 0 209715200 snapshot-origin 254:2
#ioctl/libdm-iface.c:1782         dm table   (254:0) OF   [16384]
#libdm-deptree.c:1634     Suppressed vg00-lv00 identical table reload.
#libdm-deptree.c:1601     Loading vg00-lv_snap-cow table (254:3)
#libdm-deptree.c:1547         Adding target to (254:3): 0 20971520 linear 8:16 209715584
#ioctl/libdm-iface.c:1782         dm table   (254:3) OF   [16384]
#libdm-deptree.c:1634     Suppressed vg00-lv_snap-cow identical table reload.
#libdm-deptree.c:1601     Loading vg00-lv_snap table (254:1)
#libdm-deptree.c:1547         Adding target to (254:1): 0 209715200 snapshot 254:2 254:3 P 8
#ioctl/libdm-iface.c:1782         dm table   (254:1) OF   [16384]
#libdm-deptree.c:1634     Suppressed vg00-lv_snap identical table reload.
#libdm-deptree.c:1268     Creating vg00-lv_snap1-cow
#ioctl/libdm-iface.c:1782         dm create vg00-lv_snap1-cow LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vsrnoendjEldPYGDaM59rr8EJvU5RCzpB-cow NF   [16384]
#libdm-common.c:771         vg00-lv_snap1-cow: Stacking NODE_ADD (254,5) 0:0 0600
#libdm-deptree.c:1601     Loading vg00-lv_snap1-cow table (254:5)
#libdm-deptree.c:1547         Adding target to (254:5): 0 20971520 linear 8:16 230687104
#ioctl/libdm-iface.c:1782         dm table   (254:5) OF   [16384]
#ioctl/libdm-iface.c:1782         dm reload   (254:5) NF   [16384]
#libdm-deptree.c:1641         Table size changed from 0 to 20971520 for vg00-lv_snap1-cow
#libdm-deptree.c:941     Resuming vg00-lv_snap1-cow (254:5)
#ioctl/libdm-iface.c:1782         dm resume   (254:5) NF   [16384]
#libdm-common.c:800         vg00-lv_snap1-cow: Stacking NODE_READ_AHEAD 256 (flags=1)
#libdm-deptree.c:1601     Loading vg00-lv_snap1 table (254:4)
#libdm-deptree.c:1547         Adding target to (254:4): 0 209715200 snapshot 254:2 254:5 P 8
#ioctl/libdm-iface.c:1782         dm table   (254:4) OF   [16384]
#ioctl/libdm-iface.c:1782         dm reload   (254:4) NF   [16384]
#libdm-deptree.c:1641         Table size changed from 20971520 to 209715200 for vg00-lv_snap1
#mm/memlock.c:265       Locking memory
#mm/memlock.c:176         mlock        648KiB       400000 -       4a2000 r-xp  00000000 08:02 3965044                            /sbin/lvm
#mm/memlock.c:176         mlock         28KiB       6a2000 -       6a9000 rw-p  000a2000 08:02 3965044                            /sbin/lvm
#mm/memlock.c:176         mlock      21720KiB       6a9000 -      1bdf000 rw-p  006a9000 00:00 0                                  [heap]
#mm/memlock.c:176         mlock        112KiB   332f800000 -   332f81c000 r-xp  00000000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         mlock          4KiB   332fa1b000 -   332fa1c000 r--p  0001b000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         mlock          4KiB   332fa1c000 -   332fa1d000 rw-p  0001c000 08:02 2949448                        /lib64/ld-2.5.so
#mm/memlock.c:176         mlock       1332KiB   332fc00000 -   332fd4d000 r-xp  00000000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:140         mlock area unreadable '332fd4d000-332ff4d000 ---p 0014d000 08:02 2949449                        /lib64/libc-2.5.so': Skipping.
#mm/memlock.c:176         mlock         16KiB   332ff4d000 -   332ff51000 r--p  0014d000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:176         mlock          4KiB   332ff51000 -   332ff52000 rw-p  00151000 08:02 2949449                        /lib64/libc-2.5.so
#mm/memlock.c:176         mlock         20KiB   332ff52000 -   332ff57000 rw-p  332ff52000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330400000-3330402000 r-xp 00000000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:140         mlock area unreadable '3330402000-3330602000 ---p 00002000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330602000-3330603000 r--p 00002000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:158         mlock default filter '/libdl-' matches '3330603000-3330604000 rw-p 00003000 08:02 2949315                        /lib64/libdl-2.5.so': Skipping.
#mm/memlock.c:176         mlock        236KiB   3331400000 -   333143b000 r-xp  00000000 08:02 2949463                        /lib64/libsepol.so.1
#mm/memlock.c:140         mlock area unreadable '333143b000-333163b000 ---p 0003b000 08:02 2949463                        /lib64/libsepol.so.1': Skipping.
#mm/memlock.c:176         mlock          4KiB   333163b000 -   333163c000 rw-p  0003b000 08:02 2949463                        /lib64/libsepol.so.1
#mm/memlock.c:176         mlock         40KiB   333163c000 -   3331646000 rw-p  333163c000 00:00 0 
#mm/memlock.c:176         mlock         84KiB   3331800000 -   3331815000 r-xp  00000000 08:02 2949464                        /lib64/libselinux.so.1
#mm/memlock.c:140         mlock area unreadable '3331815000-3331a15000 ---p 00015000 08:02 2949464                        /lib64/libselinux.so.1': Skipping.
#mm/memlock.c:176         mlock          8KiB   3331a15000 -   3331a17000 rw-p  00015000 08:02 2949464                        /lib64/libselinux.so.1
#mm/memlock.c:176         mlock          4KiB   3331a17000 -   3331a18000 rw-p  3331a17000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libreadline.so.' matches '3331c00000-3331c35000 r-xp 00000000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:140         mlock area unreadable '3331c35000-3331e34000 ---p 00035000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:158         mlock default filter '/libreadline.so.' matches '3331e34000-3331e3c000 rw-p 00034000 08:02 4038780                        /usr/lib64/libreadline.so.5.1': Skipping.
#mm/memlock.c:176         mlock          4KiB   3331e3c000 -   3331e3d000 rw-p  3331e3c000 00:00 0 
#mm/memlock.c:158         mlock default filter '/libncurses.so.' matches '3342600000-334264f000 r-xp 00000000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:140         mlock area unreadable '334264f000-334284e000 ---p 0004f000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:158         mlock default filter '/libncurses.so.' matches '334284e000-334285c000 rw-p 0004e000 08:02 4052040                        /usr/lib64/libncurses.so.5.5': Skipping.
#mm/memlock.c:176         mlock          4KiB   334285c000 -   334285d000 rw-p  334285c000 00:00 0 
#mm/memlock.c:158         mlock default filter 'locale/locale-archive' matches '7f05d02fc000-7f05d38d4000 r--p 00000000 08:02 4034433                    /usr/lib/locale/locale-archive': Skipping.
#mm/memlock.c:176         mlock         16KiB 7f05d38d4000 - 7f05d38d8000 rw-p  7f05d38d4000 00:00 0 
#mm/memlock.c:176         mlock        112KiB 7f05d38d8000 - 7f05d38f4000 r-xp  00000000 08:02 5668894                    /lib/libdevmapper.so.1.02
#mm/memlock.c:140         mlock area unreadable '7f05d38f4000-7f05d3af4000 ---p 0001c000 08:02 5668894                    /lib/libdevmapper.so.1.02': Skipping.
#mm/memlock.c:176         mlock          8KiB 7f05d3af4000 - 7f05d3af6000 rw-p  0001c000 08:02 5668894                    /lib/libdevmapper.so.1.02
#mm/memlock.c:176         mlock          4KiB 7f05d3b07000 - 7f05d3b08000 rw-p  7f05d3b07000 00:00 0 
#mm/memlock.c:158         mlock default filter 'gconv/gconv-modules.cache' matches '7f05d3b08000-7f05d3b0f000 r--s 00000000 08:02 4161798                    /usr/lib64/gconv/gconv-modules.cache': Skipping.
#mm/memlock.c:176         mlock          8KiB 7f05d3b0f000 - 7f05d3b11000 rw-p  7f05d3b0f000 00:00 0 
#mm/memlock.c:176         mlock        276KiB 7fffdbacc000 - 7fffdbb11000 rw-p  7ffffffba000 00:00 0                      [stack]
#mm/memlock.c:148         mlock ignore filter '[vdso]' matches '7fffdbbff000-7fffdbc00000 r-xp 7fffdbbff000 00:00 0                      [vdso]': Skipping.
#mm/memlock.c:148         mlock ignore filter '[vsyscall]' matches 'ffffffffff600000-ffffffffff601000 r-xp 00000000 00:00 0                  [vsyscall]': Skipping.
#mm/memlock.c:232         Locked 25288704 bytes
#mm/memlock.c:318         memlock_count inc to 1
#activate/dev_manager.c:769         Getting device info for vg00-lv00 [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:0) OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:2) OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv00-real [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-real]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-real OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv00-cow [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-cow]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-cow OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vkN16gg6gtMNvqV3ftscJJ5gQyQ8LlQmA-cow OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0 OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:1) OF   [16384]
#ioctl/libdm-iface.c:1782         dm deps   (254:3) OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap-real [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-real]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-real OF   [16384]
#ioctl/libdm-iface.c:1782         dm info  t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-real OF   [16384]
#activate/dev_manager.c:769         Getting device info for vg00-lv_snap-cow [LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-cow]
#ioctl/libdm-iface.c:1782         dm info  LVM-t1AHib0Nn5foWd16imXIJYi137IRLQ0vzyVldo4zXAGYRBbz18O2FlRjZTpfsvt0-cow OF   [16384]
#ioctl/libdm-iface.c:1782         dm info   (254:0) NF   [16384]
#libdm-deptree.c:986     Suspending vg00-lv00 (254:0) with filesystem sync with device flush
#ioctl/libdm-iface.c:1782         dm suspend   (254:0) NF   [16384]
-------------- next part --------------
[root at new-host ~]# vgs -vvvv
#lvmcmdline.c:1049         Processing: vgs -vvvv
#lvmcmdline.c:1052         O_DIRECT will be used
#config/config.c:987       Setting global/locking_type to 1
#config/config.c:992       global/wait_for_locks not found in config: defaulting to 1
#locking/locking.c:242       File-based locking selected.
#config/config.c:964       Setting global/locking_dir to /var/lock/lvm
#config/config.c:992       report/aligned not found in config: defaulting to 1
#config/config.c:992       report/buffered not found in config: defaulting to 1
#config/config.c:992       report/headings not found in config: defaulting to 1
#config/config.c:970       report/separator not found in config: defaulting to  
#config/config.c:992       report/prefixes not found in config: defaulting to 0
#config/config.c:992       report/quoted not found in config: defaulting to 1
#config/config.c:992       report/columns_as_rows not found in config: defaulting to 0
#config/config.c:970       report/vgs_sort not found in config: defaulting to vg_name
#config/config.c:970       report/vgs_cols_verbose not found in config: defaulting to vg_name,vg_attr,vg_extent_size,pv_count,lv_count,snap_count,vg_size,vg_free,vg_uuid
#toollib.c:514     Finding all volume groups
#device/dev-io.c:487         Opened /dev/ramdisk RO O_DIRECT
#device/dev-io.c:134         /dev/ramdisk: block size is 4096 bytes
#label/label.c:184       /dev/ramdisk: No label detected
#device/dev-io.c:533         Closed /dev/ramdisk
#device/dev-io.c:487         Opened /dev/ram RO O_DIRECT
#device/dev-io.c:134         /dev/ram: block size is 4096 bytes
#label/label.c:184       /dev/ram: No label detected
#device/dev-io.c:533         Closed /dev/ram
#device/dev-io.c:487         Opened /dev/sda1 RO O_DIRECT
#device/dev-io.c:134         /dev/sda1: block size is 1024 bytes
#label/label.c:184       /dev/sda1: No label detected
#device/dev-io.c:533         Closed /dev/sda1
#device/dev-io.c:487         Opened /dev/ram2 RO O_DIRECT
#device/dev-io.c:134         /dev/ram2: block size is 4096 bytes
#label/label.c:184       /dev/ram2: No label detected
#device/dev-io.c:533         Closed /dev/ram2
#device/dev-io.c:487         Opened /dev/root RO O_DIRECT
#device/dev-io.c:134         /dev/root: block size is 4096 bytes
#label/label.c:184       /dev/root: No label detected
#device/dev-io.c:533         Closed /dev/root
#device/dev-io.c:487         Opened /dev/ram3 RO O_DIRECT
#device/dev-io.c:134         /dev/ram3: block size is 4096 bytes
#label/label.c:184       /dev/ram3: No label detected
#device/dev-io.c:533         Closed /dev/ram3
#device/dev-io.c:487         Opened /dev/sda3 RO O_DIRECT
#device/dev-io.c:134         /dev/sda3: block size is 4096 bytes
#label/label.c:184       /dev/sda3: No label detected
#device/dev-io.c:533         Closed /dev/sda3
#device/dev-io.c:487         Opened /dev/ram4 RO O_DIRECT
#device/dev-io.c:134         /dev/ram4: block size is 4096 bytes
#label/label.c:184       /dev/ram4: No label detected
#device/dev-io.c:533         Closed /dev/ram4
#device/dev-io.c:487         Opened /dev/ram5 RO O_DIRECT
#device/dev-io.c:134         /dev/ram5: block size is 4096 bytes
#label/label.c:184       /dev/ram5: No label detected
#device/dev-io.c:533         Closed /dev/ram5
#device/dev-io.c:487         Opened /dev/sda5 RO O_DIRECT
#device/dev-io.c:134         /dev/sda5: block size is 1024 bytes
#label/label.c:184       /dev/sda5: No label detected
#device/dev-io.c:533         Closed /dev/sda5
#device/dev-io.c:487         Opened /dev/ram6 RO O_DIRECT
#device/dev-io.c:134         /dev/ram6: block size is 4096 bytes
#label/label.c:184       /dev/ram6: No label detected
#device/dev-io.c:533         Closed /dev/ram6
#device/dev-io.c:487         Opened /dev/ram7 RO O_DIRECT
#device/dev-io.c:134         /dev/ram7: block size is 4096 bytes
#label/label.c:184       /dev/ram7: No label detected
#device/dev-io.c:533         Closed /dev/ram7
#device/dev-io.c:487         Opened /dev/ram8 RO O_DIRECT
#device/dev-io.c:134         /dev/ram8: block size is 4096 bytes
#label/label.c:184       /dev/ram8: No label detected
#device/dev-io.c:533         Closed /dev/ram8
#device/dev-io.c:487         Opened /dev/ram9 RO O_DIRECT
#device/dev-io.c:134         /dev/ram9: block size is 4096 bytes
#label/label.c:184       /dev/ram9: No label detected
#device/dev-io.c:533         Closed /dev/ram9
#device/dev-io.c:487         Opened /dev/ram10 RO O_DIRECT
#device/dev-io.c:134         /dev/ram10: block size is 4096 bytes
#label/label.c:184       /dev/ram10: No label detected
#device/dev-io.c:533         Closed /dev/ram10
#device/dev-io.c:487         Opened /dev/ram11 RO O_DIRECT
#device/dev-io.c:134         /dev/ram11: block size is 4096 bytes
#label/label.c:184       /dev/ram11: No label detected
#device/dev-io.c:533         Closed /dev/ram11
#device/dev-io.c:487         Opened /dev/ram12 RO O_DIRECT
#device/dev-io.c:134         /dev/ram12: block size is 4096 bytes
#label/label.c:184       /dev/ram12: No label detected
#device/dev-io.c:533         Closed /dev/ram12
#device/dev-io.c:487         Opened /dev/ram13 RO O_DIRECT
#device/dev-io.c:134         /dev/ram13: block size is 4096 bytes
#label/label.c:184       /dev/ram13: No label detected
#device/dev-io.c:533         Closed /dev/ram13
#device/dev-io.c:487         Opened /dev/ram14 RO O_DIRECT
#device/dev-io.c:134         /dev/ram14: block size is 4096 bytes
#label/label.c:184       /dev/ram14: No label detected
#device/dev-io.c:533         Closed /dev/ram14
#device/dev-io.c:487         Opened /dev/ram15 RO O_DIRECT
#device/dev-io.c:134         /dev/ram15: block size is 4096 bytes
#label/label.c:184       /dev/ram15: No label detected
#device/dev-io.c:533         Closed /dev/ram15
#device/dev-io.c:487         Opened /dev/sdb RO O_DIRECT
#device/dev-io.c:134         /dev/sdb: block size is 4096 bytes
#label/label.c:160       /dev/sdb: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sdb: Found metadata at 16896 size 2642 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdb: now in VG vg00 with 1 mdas
#cache/lvmcache.c:873         lvmcache: /dev/sdb: setting vg00 VGID to t1AHib0Nn5foWd16imXIJYi137IRLQ0v
#cache/lvmcache.c:1123         lvmcache: /dev/sdb: VG vg00: Set creation host to new-host.
#device/dev-io.c:533         Closed /dev/sdb
#device/dev-io.c:487         Opened /dev/sdc RO O_DIRECT
#device/dev-io.c:134         /dev/sdc: block size is 4096 bytes
#label/label.c:160       /dev/sdc: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sdc: Found metadata at 16896 size 2642 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdc: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#device/dev-io.c:533         Closed /dev/sdc
#device/dev-io.c:487         Opened /dev/sdd RO O_DIRECT
#device/dev-io.c:134         /dev/sdd: block size is 4096 bytes
#label/label.c:160       /dev/sdd: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sdd: Found metadata at 16896 size 2642 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sdd: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#device/dev-io.c:533         Closed /dev/sdd
#device/dev-io.c:487         Opened /dev/sde RO O_DIRECT
#device/dev-io.c:134         /dev/sde: block size is 4096 bytes
#label/label.c:160       /dev/sde: lvm2 label detected
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG #orphans_lvm2 (#orphans_lvm2)
#format_text/format-text.c:1137         /dev/sde: Found metadata at 16896 size 2642 (in area at 4096 size 192512) for vg00 (t1AHib-0Nn5-foWd-16im-XIJY-i137-IRLQ0v)
#cache/lvmcache.c:1086         lvmcache: /dev/sde: now in VG vg00 (t1AHib0Nn5foWd16imXIJYi137IRLQ0v) with 1 mdas
#device/dev-io.c:533         Closed /dev/sde
#toollib.c:427     Finding volume group "vg00"
#locking/file_locking.c:235       Locking /var/lock/lvm/V_vg00 RB
#locking/file_locking.c:141         _do_flock /var/lock/lvm/V_vg00:aux WB
#locking/file_locking.c:51         _undo_flock /var/lock/lvm/V_vg00:aux
#locking/file_locking.c:141         _do_flock /var/lock/lvm/V_vg00 RB


More information about the dm-devel mailing list