Jul 25 04:35:00 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 04:35:00 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 04:35:00 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31 Jul 25 04:35:00 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:00 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526672.939409321' Jul 25 04:35:00 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526673.337719269' Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29 Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526673.509936036' Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526673.622557493' Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526673.751251448' Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526673.916340463' Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:01 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526674.119660123' Jul 25 04:35:01 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526674.261963595' Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799 Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526674.40139581' Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526674.515580857' Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8 Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526674.658492921' Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526674.795443447' Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526674.911906207' Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526675.01251127' Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:02 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:02 xcp-50-191 SM: [799270][MainThread] lock: opening lock file /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] LVMCache created for VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700 Jul 25 04:35:03 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:03 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526675.168194487' Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:03 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] Entering _checkMetadataVolume Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] LVMCache: will initialize now Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] LVMCache: refreshing Jul 25 04:35:03 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:35:03 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '799270 - 4526675.298707227' Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] pread SUCCESS Jul 25 04:35:03 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:35:03 xcp-50-191 SM: [799270][MainThread] lock: closed /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr Jul 25 04:45:20 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31 Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527293.185481369' Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527293.517870218' Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29 Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527293.717633226' Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527293.824465584' Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527293.922994258' Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527294.043829619' Jul 25 04:45:21 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527294.257158476' Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527294.379109622' Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799 Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527294.615189988' Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527294.739868812' Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8 Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527294.889623533' Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527294.983725885' Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527295.101945848' Jul 25 04:45:22 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527295.171610372' Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: opening lock file /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] LVMCache created for VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700 Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527295.27972048' Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] Entering _checkMetadataVolume Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] LVMCache: will initialize now Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] LVMCache: refreshing Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '803590 - 4527295.333789591' Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] pread SUCCESS Jul 25 04:45:23 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:45:23 xcp-50-191 SM: [803590][MainThread] lock: closed /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31 Jul 25 04:55:46 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:46 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527918.74936536' Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:46 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:46 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:46 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527919.109332361' Jul 25 04:55:46 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29 Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527919.288224906' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527919.413290502' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527919.554547474' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527919.677164096' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527919.800858832' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527919.867278302' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799 Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.050965989' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:47 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.149519788' Jul 25 04:55:47 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8 Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.276830974' Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.365456589' Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.46831699' Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.530679212' Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: opening lock file /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache created for VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700 Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.644484505' Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] Entering _checkMetadataVolume Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache: will initialize now Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] LVMCache: refreshing Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '807952 - 4527920.718847967' Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] pread SUCCESS Jul 25 04:55:48 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:55:48 xcp-50-191 SM: [807952][MainThread] lock: closed /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809220 - 4528087.279060129' Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] Entering _checkMetadataVolume Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] LVMCache: will initialize now Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] LVMCache: refreshing Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528087.4459765' Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] Entering _checkMetadataVolume Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] LVMCache: will initialize now Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] LVMCache: refreshing Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809220 - 4528087.588923215' Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528087.663604552' Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] vdi_deactivate {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_deactivate', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001cdbfff3011482210fa494-part3'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:5a97a702-b80e-25c8-a630-e983920baa2f', 'sr_uuid': '9839b298-0487-972f-2eef-509d0193ed39', 'vdi_ref': 'OpaqueRef:be9e937c-2ccd-1b31-07e6-381c0d5d237e', 'vdi_location': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'vdi_uuid': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'subtask_of': 'DummyRef:|57be4686-3226-79ec-1f79-bdac0d3f9955|VDI.deactivate', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] lock: opening lock file /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] blktap2.deactivate Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] lock: acquired /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] ['/usr/sbin/tap-ctl', 'close', '-p', '372231', '-m', '5', '-t', '120'] Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:35 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] vdi_deactivate {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_deactivate', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001c547b0db3a0caa7c7e004'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:17e9a0b6-81e6-2627-d1d2-bbd21e07cb85', 'sr_uuid': 'b0c68dbe-de25-8245-fa27-0ef4b005d39a', 'vdi_ref': 'OpaqueRef:5cf402c5-3d34-cc30-37cc-205ff5a8ac01', 'vdi_location': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'vdi_uuid': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'subtask_of': 'DummyRef:|7f7a9afa-9d73-7b31-f215-3dd241a96147|VDI.deactivate', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] lock: opening lock file /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] blktap2.deactivate Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] lock: acquired /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] = 0 Jul 25 04:58:35 xcp-50-191 SM: [809220][MainThread] ['/usr/sbin/tap-ctl', 'detach', '-p', '372231', '-m', '5'] Jul 25 04:58:35 xcp-50-191 SM: [809225][MainThread] ['/usr/sbin/tap-ctl', 'close', '-p', '372284', '-m', '6', '-t', '120'] Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] = 0 Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] ['/usr/sbin/tap-ctl', 'free', '-m', '5'] Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] = 0 Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] tap.deactivate: Shut down Tapdisk(vhd:/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a, pid=372231, minor=5, state=R) Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809220 - 4528088.279935152' Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] = 0 Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['/usr/sbin/tap-ctl', 'detach', '-p', '372284', '-m', '6'] Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Entering _checkMetadataVolume Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] LVMCache: will initialize now Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] LVMCache: refreshing Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809220 - 4528088.376236564' Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809220 - 4528088.459876158' Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] = 0 Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['/usr/sbin/tap-ctl', 'free', '-m', '6'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] = 0 Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] tap.deactivate: Shut down Tapdisk(qcow2:/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e, pid=372284, minor=6, state=R) Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] LVMVDI.detach for e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: opening lock file /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a (0, 1) + (1, 0) => (1, 1) Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a set => (1, 1b) Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] ['/usr/bin/vhd-util', 'query', '--debug', '-vsaf', '-n', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a'] Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] ['/usr/bin/vhd-util', 'scan', '-f', '-m', 'VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a', '-l', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39', '-a'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528088.621184516' Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] Entering _checkMetadataVolume Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] LVMCache: will initialize now Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] LVMCache: refreshing Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528088.693574434' Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a (1, 1) + (-1, 0) => (0, 1) Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a set => (0, 1b) Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a (0, 1) + (0, -1) => (0, 0) Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a set => (0, 0b) Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809220 - 4528088.749807374' Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] ['/sbin/lvchange', '-an', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a'] Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528088.924701487' Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809220 - 4528089.024889374' Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--9839b298--0487--972f--2eef--509d0193ed39-VHD--e1e4fe94--48d5--4c98--b222--85faf81e411a'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] LVMVDI.detach for d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] lock: opening lock file /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (0, 1) + (1, 0) => (1, 1) Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (1, 1b) Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] Removed host key host_OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5 for e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: released /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: closed /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:58:36 xcp-50-191 SM: [809220][MainThread] lock: closed /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:36 xcp-50-191 SM: [809225][MainThread] ['/opt/xensource/libexec/qcow2_helper', 'allocated', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:58:37 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:37 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809492 - 4528089.35766703' Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] pread SUCCESS Jul 25 04:58:37 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] Entering _checkMetadataVolume Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] LVMCache: will initialize now Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] LVMCache: refreshing Jul 25 04:58:37 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:37 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809492 - 4528089.467678827' Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] pread SUCCESS Jul 25 04:58:37 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] vdi_detach {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_detach', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001cdbfff3011482210fa494-part3'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:5a97a702-b80e-25c8-a630-e983920baa2f', 'sr_uuid': '9839b298-0487-972f-2eef-509d0193ed39', 'vdi_ref': 'OpaqueRef:be9e937c-2ccd-1b31-07e6-381c0d5d237e', 'vdi_location': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'vdi_uuid': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'subtask_of': 'DummyRef:|55e00bec-c8f6-5d02-faae-ce41263bb4f3|VDI.detach', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] lock: opening lock file /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:58:37 xcp-50-191 SM: [809492][MainThread] lock: closed /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] ['/usr/bin/qemu-img', 'measure', '-O', 'qcow2', '--output', 'json', '-o', 'cluster_size=65536', '--size', '6761996550144'] Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] LVMCache: refreshing Jul 25 04:58:54 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:54 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528106.375975084' Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:54 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] LVMCache: refreshing Jul 25 04:58:54 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:58:54 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528106.498524064' Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:54 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (1, 1) + (1, 0) => (2, 1) Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (2, 1b) Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:58:54 xcp-50-191 SM: [809225][MainThread] ['/opt/xensource/libexec/qcow2_helper', 'allocated', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (2, 1) + (-1, 0) => (1, 1) Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (1, 1b) Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (1, 1) + (-1, 0) => (0, 1) Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (0, 1b) Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (0, 1) + (0, -1) => (0, 0) Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (0, 0b) Jul 25 04:59:11 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:11 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528123.95726148' Jul 25 04:59:11 xcp-50-191 SM: [809225][MainThread] ['/sbin/lvchange', '-an', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809225 - 4528124.169510115' Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--b0c68dbe--de25--8245--fa27--0ef4b005d39a-QCOW2--d202a2b3--97c4--4c19--8c76--14e23278064e'] Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] pread SUCCESS Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] Removed host key host_OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5 for d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] lock: released /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] lock: closed /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:12 xcp-50-191 SM: [809225][MainThread] lock: closed /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809758 - 4528124.572941323' Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] pread SUCCESS Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] Entering _checkMetadataVolume Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] LVMCache: will initialize now Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] LVMCache: refreshing Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809758 - 4528124.685965424' Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] pread SUCCESS Jul 25 04:59:12 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] vdi_detach {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_detach', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001c547b0db3a0caa7c7e004'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:17e9a0b6-81e6-2627-d1d2-bbd21e07cb85', 'sr_uuid': 'b0c68dbe-de25-8245-fa27-0ef4b005d39a', 'vdi_ref': 'OpaqueRef:5cf402c5-3d34-cc30-37cc-205ff5a8ac01', 'vdi_location': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'vdi_uuid': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'subtask_of': 'DummyRef:|8752fbf8-3ddd-7cd6-aa61-b663b8f80b2f|VDI.detach', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] lock: opening lock file /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:12 xcp-50-191 SM: [809758][MainThread] lock: closed /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809807 - 4528128.798975865' Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] pread SUCCESS Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809805 - 4528128.927916766' Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] lock: acquired /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] LVMCache: will initialize now Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] LVMCache: refreshing Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] pread SUCCESS Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] lock: acquired /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809807 - 4528128.993859937' Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] LVMCache: will initialize now Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] LVMCache: refreshing Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] pread SUCCESS Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809805 - 4528129.047516897' Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] lock: released /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] Entering _checkMetadataVolume Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] vdi_epoch_end {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_epoch_end', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001cdbfff3011482210fa494-part3'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:5a97a702-b80e-25c8-a630-e983920baa2f', 'sr_uuid': '9839b298-0487-972f-2eef-509d0193ed39', 'vdi_ref': 'OpaqueRef:be9e937c-2ccd-1b31-07e6-381c0d5d237e', 'vdi_location': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'vdi_uuid': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'subtask_of': 'DummyRef:|911abcef-8053-5707-10c4-ffb34a1a103f|VDI.epoch_end', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:16 xcp-50-191 SM: [809807][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] pread SUCCESS Jul 25 04:59:16 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] lock: released /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] Entering _checkMetadataVolume Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] vdi_epoch_end {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_epoch_end', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001c547b0db3a0caa7c7e004'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:17e9a0b6-81e6-2627-d1d2-bbd21e07cb85', 'sr_uuid': 'b0c68dbe-de25-8245-fa27-0ef4b005d39a', 'vdi_ref': 'OpaqueRef:5cf402c5-3d34-cc30-37cc-205ff5a8ac01', 'vdi_location': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'vdi_uuid': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'subtask_of': 'DummyRef:|d6dd6789-aab9-5ecf-6bdc-70bec2845db8|VDI.epoch_end', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:16 xcp-50-191 SM: [809805][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:19 xcp-50-191 SM: [809904][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:19 xcp-50-191 SM: [809907][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:19 xcp-50-191 SM: [809904][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:59:19 xcp-50-191 SM: [809907][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:19 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:19 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809904 - 4528132.005973849' Jul 25 04:59:19 xcp-50-191 SM: [809904][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:19 xcp-50-191 SM: [809904][MainThread] pread SUCCESS Jul 25 04:59:19 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:19 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:19 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809907 - 4528132.103466393' Jul 25 04:59:19 xcp-50-191 SM: [809904][MainThread] lock: acquired /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:19 xcp-50-191 SM: [809907][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:19 xcp-50-191 SM: [809904][MainThread] LVMCache: will initialize now Jul 25 04:59:19 xcp-50-191 SM: [809904][MainThread] LVMCache: refreshing Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] pread SUCCESS Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] lock: acquired /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809904 - 4528132.164321177' Jul 25 04:59:20 xcp-50-191 SM: [809904][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] LVMCache: will initialize now Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] LVMCache: refreshing Jul 25 04:59:20 xcp-50-191 SM: [809904][MainThread] pread SUCCESS Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '809907 - 4528132.217785388' Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:20 xcp-50-191 SM: [809904][MainThread] lock: released /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:20 xcp-50-191 SM: [809904][MainThread] Entering _checkMetadataVolume Jul 25 04:59:20 xcp-50-191 SM: [809904][MainThread] vdi_epoch_begin {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_epoch_begin', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001cdbfff3011482210fa494-part3'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:5a97a702-b80e-25c8-a630-e983920baa2f', 'sr_uuid': '9839b298-0487-972f-2eef-509d0193ed39', 'vdi_ref': 'OpaqueRef:be9e937c-2ccd-1b31-07e6-381c0d5d237e', 'vdi_location': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'vdi_uuid': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'subtask_of': 'DummyRef:|67066655-7a76-fd9e-a1e8-311df389fd3e|VDI.epoch_begin', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:20 xcp-50-191 SM: [809904][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] pread SUCCESS Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] lock: released /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] Entering _checkMetadataVolume Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] vdi_epoch_begin {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_epoch_begin', 'args': [], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001c547b0db3a0caa7c7e004'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:17e9a0b6-81e6-2627-d1d2-bbd21e07cb85', 'sr_uuid': 'b0c68dbe-de25-8245-fa27-0ef4b005d39a', 'vdi_ref': 'OpaqueRef:5cf402c5-3d34-cc30-37cc-205ff5a8ac01', 'vdi_location': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'vdi_uuid': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'subtask_of': 'DummyRef:|45591996-bff7-ad0d-6c3c-c7e21c8a09e9|VDI.epoch_begin', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:20 xcp-50-191 SM: [809907][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810029 - 4528132.521988192' Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] pread SUCCESS Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] Entering _checkMetadataVolume Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810034 - 4528132.63936144' Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] LVMCache: will initialize now Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] LVMCache: refreshing Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] pread SUCCESS Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] Entering _checkMetadataVolume Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] LVMCache: will initialize now Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] LVMCache: refreshing Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810029 - 4528132.715326765' Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] pread SUCCESS Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810034 - 4528132.801762014' Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] vdi_attach {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_attach', 'args': ['true'], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001cdbfff3011482210fa494-part3'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:5a97a702-b80e-25c8-a630-e983920baa2f', 'sr_uuid': '9839b298-0487-972f-2eef-509d0193ed39', 'vdi_ref': 'OpaqueRef:be9e937c-2ccd-1b31-07e6-381c0d5d237e', 'vdi_location': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'vdi_uuid': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'subtask_of': 'DummyRef:|db0c89bc-dd79-02fe-813d-1084aae93376|VDI.attach2', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] lock: opening lock file /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] result: {'params': '/dev/sm/backend/9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a', 'params_nbd': 'nbd:unix:/run/blktap-control/nbd/9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a:exportname=e1e4fe94-48d5-4c98-b222-85faf81e411a', 'o_direct': True, 'o_direct_reason': 'SR_NOT_SUPPORTED', 'xenstore_data': {'vdi-uuid': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'scsi/0x12/0x80': 'AIAAEmUxZTRmZTk0LTQ4ZDUtNGMgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIGUxZTRmZTk0LTQ4ZDUtNGM5OC1iMjIyLTg1ZmFmODFlNDExYSA=', 'mem-pool': '9839b298-0487-972f-2eef-509d0193ed39'}} Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] pread SUCCESS Jul 25 04:59:20 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:20 xcp-50-191 SM: [810029][MainThread] lock: closed /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] vdi_attach {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_attach', 'args': ['true'], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001c547b0db3a0caa7c7e004'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:17e9a0b6-81e6-2627-d1d2-bbd21e07cb85', 'sr_uuid': 'b0c68dbe-de25-8245-fa27-0ef4b005d39a', 'vdi_ref': 'OpaqueRef:5cf402c5-3d34-cc30-37cc-205ff5a8ac01', 'vdi_location': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'vdi_uuid': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'subtask_of': 'DummyRef:|7c240358-a78b-d2d4-29e8-cea39abab422|VDI.attach2', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] lock: opening lock file /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] result: {'params': '/dev/sm/backend/b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e', 'params_nbd': 'nbd:unix:/run/blktap-control/nbd/b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e:exportname=d202a2b3-97c4-4c19-8c76-14e23278064e', 'o_direct': True, 'o_direct_reason': 'SR_NOT_SUPPORTED', 'xenstore_data': {'vdi-uuid': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'scsi/0x12/0x80': 'AIAAEmQyMDJhMmIzLTk3YzQtNGMgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIGQyMDJhMmIzLTk3YzQtNGMxOS04Yzc2LTE0ZTIzMjc4MDY0ZSA=', 'mem-pool': 'b0c68dbe-de25-8245-fa27-0ef4b005d39a'}} Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:20 xcp-50-191 SM: [810034][MainThread] lock: closed /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528133.261303926' Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] Entering _checkMetadataVolume Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528133.389179553' Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] LVMCache: will initialize now Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] LVMCache: refreshing Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] Entering _checkMetadataVolume Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528133.446501592' Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] LVMCache: will initialize now Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] LVMCache: refreshing Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528133.503652' Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] vdi_activate {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_activate', 'args': ['true'], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001cdbfff3011482210fa494-part3'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:5a97a702-b80e-25c8-a630-e983920baa2f', 'sr_uuid': '9839b298-0487-972f-2eef-509d0193ed39', 'vdi_ref': 'OpaqueRef:be9e937c-2ccd-1b31-07e6-381c0d5d237e', 'vdi_location': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'vdi_uuid': 'e1e4fe94-48d5-4c98-b222-85faf81e411a', 'subtask_of': 'DummyRef:|c78f1f1e-0e4c-5b3a-ec2c-04977d46104e|VDI.activate', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] lock: opening lock file /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] blktap2.activate Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] lock: acquired /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] Adding tag to: e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] Activate lock succeeded Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] vdi_activate {'host_ref': 'OpaqueRef:17f66e86-b0fe-1f11-335b-4c30a6ff8dc5', 'command': 'vdi_activate', 'args': ['true'], 'device_config': {'SRmaster': 'true', 'device': '/dev/disk/by-id/scsi-3600508b1001c547b0db3a0caa7c7e004'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:17e9a0b6-81e6-2627-d1d2-bbd21e07cb85', 'sr_uuid': 'b0c68dbe-de25-8245-fa27-0ef4b005d39a', 'vdi_ref': 'OpaqueRef:5cf402c5-3d34-cc30-37cc-205ff5a8ac01', 'vdi_location': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'vdi_uuid': 'd202a2b3-97c4-4c19-8c76-14e23278064e', 'subtask_of': 'DummyRef:|d9e24072-6c6a-370b-908f-976416db8553|VDI.activate', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false'} Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] lock: opening lock file /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] blktap2.activate Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] Adding tag to: d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] Activate lock succeeded Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528133.643951331' Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] Entering _checkMetadataVolume Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] LVMCache: will initialize now Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528133.734454627' Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] LVMCache: refreshing Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] Entering _checkMetadataVolume Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] LVMCache: will initialize now Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] LVMCache: refreshing Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528133.789914916' Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528133.841193121' Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] LVMVDI.attach for e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] lock: opening lock file /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a (0, 0) + (1, 0) => (1, 0) Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a set => (1, 0b) Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528133.897079043' Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a'] Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] LVMVDI.attach for d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] lock: opening lock file /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (0, 0) + (1, 0) => (1, 0) Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (1, 0b) Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528133.95575376' Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:21 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:21 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528134.064916409' Jul 25 04:59:21 xcp-50-191 SM: [810052][MainThread] ['/sbin/lvchange', '--refresh', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528134.171611004' Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] ['/sbin/lvchange', '--refresh', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528134.303828198' Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['/sbin/dmsetup', 'table', 'VG_XenStorage--9839b298--0487--972f--2eef--509d0193ed39-VHD--e1e4fe94--48d5--4c98--b222--85faf81e411a'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] DM table for /dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a: 0 42041344 linear 8:67 168196096 Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] 42041344 63037440 linear 8:67 483538944 Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528134.317930163' Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] ['/sbin/dmsetup', 'table', 'VG_XenStorage--b0c68dbe--de25--8245--fa27--0ef4b005d39a-QCOW2--d202a2b3--97c4--4c19--8c76--14e23278064e'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['/usr/bin/vhd-util', 'query', '--debug', '-vsaf', '-n', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a'] Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] DM table for /dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e: 0 13209042944 linear 8:96 32768 Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['/usr/bin/vhd-util', 'scan', '-f', '-m', 'VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a', '-l', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39', '-a'] Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 SM: [810058][MainThread] ['/opt/xensource/libexec/qcow2_helper', 'allocated', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a (1, 0) + (0, 1) => (1, 1) Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a set => (1, 1b) Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a (1, 1) + (-1, 0) => (0, 1) Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] Refcount for lvm-9839b298-0487-972f-2eef-509d0193ed39:e1e4fe94-48d5-4c98-b222-85faf81e411a set => (0, 1b) Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] PhyLink(/dev/sm/phy/9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a) -> /dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: opening lock file /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/lvchange-p Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: acquired /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/lvchange-p Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810052 - 4528134.532099979' Jul 25 04:59:22 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['/usr/sbin/tap-ctl', 'allocate'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] = 0 Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['/usr/sbin/tap-ctl', 'spawn'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] = 0 Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['cgclassify', '810169'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] pread SUCCESS Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['/usr/sbin/tap-ctl', 'attach', '-p', '810169', '-m', '5'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] = 0 Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] ['/usr/sbin/tap-ctl', 'open', '-p', '810169', '-m', '5', '-a', 'vhd:/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a'] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] = 0 Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] Set scheduler to [noop] on [/sys/dev/block/254:5] Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] tap.activate: Launched Tapdisk(vhd:/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39/VHD-e1e4fe94-48d5-4c98-b222-85faf81e411a, pid=810169, minor=5, state=R) Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: released /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/lvchange-p Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] Removed activating flag from e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] DeviceNode(/dev/sm/backend/9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a) -> /dev/xen/blktap-2/tapdev5 Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] NBDLink(/run/blktap-control/nbd/9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a) -> /run/blktap-control/nbd810169.5 Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: released /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: closed /var/lock/sm/e1e4fe94-48d5-4c98-b222-85faf81e411a/vdi Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: closed /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/e1e4fe94-48d5-4c98-b222-85faf81e411a Jul 25 04:59:22 xcp-50-191 SM: [810052][MainThread] lock: closed /var/lock/sm/lvm-9839b298-0487-972f-2eef-509d0193ed39/lvchange-p Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] ['/usr/bin/qemu-img', 'measure', '-O', 'qcow2', '--output', 'json', '-o', 'cluster_size=65536', '--size', '6761996550144'] Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] LVMCache: refreshing Jul 25 04:59:39 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:39 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528152.086112895' Jul 25 04:59:39 xcp-50-191 SM: [810058][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:40 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] LVMCache: refreshing Jul 25 04:59:40 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:40 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528152.188271878' Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:40 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (1, 0) + (1, 0) => (2, 0) Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (2, 0b) Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:40 xcp-50-191 SM: [810058][MainThread] ['/opt/xensource/libexec/qcow2_helper', 'allocated', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (2, 0) + (-1, 0) => (1, 0) Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (1, 0b) Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (1, 0) + (0, 1) => (1, 1) Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (1, 1b) Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e (1, 1) + (-1, 0) => (0, 1) Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] Refcount for lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a:d202a2b3-97c4-4c19-8c76-14e23278064e set => (0, 1b) Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] PhyLink(/dev/sm/phy/b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e) -> /dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] ['blockdev', '--getsize64', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] VDI d202a2b3-97c4-4c19-8c76-14e23278064e is only a leaf, continuing... Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: opening lock file /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/lvchange-p Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] lock: acquired /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/lvchange-p Jul 25 04:59:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 04:59:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '810058 - 4528169.715791382' Jul 25 04:59:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] ['/usr/sbin/tap-ctl', 'allocate'] Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] = 0 Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] ['/usr/sbin/tap-ctl', 'spawn'] Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] = 0 Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] ['cgclassify', '810455'] Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] pread SUCCESS Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] ['/usr/sbin/tap-ctl', 'attach', '-p', '810455', '-m', '6'] Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] = 0 Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] ['/usr/sbin/tap-ctl', 'open', '-p', '810455', '-m', '6', '-a', 'qcow2:/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e'] Jul 25 04:59:57 xcp-50-191 SM: [810058][MainThread] = 0 Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] Set scheduler to [noop] on [/sys/dev/block/254:6] Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] tap.activate: Launched Tapdisk(qcow2:/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a/QCOW2-d202a2b3-97c4-4c19-8c76-14e23278064e, pid=810455, minor=6, state=R) Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/lvchange-p Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] Removed activating flag from d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] DeviceNode(/dev/sm/backend/b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e) -> /dev/xen/blktap-2/tapdev6 Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] NBDLink(/run/blktap-control/nbd/b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e) -> /run/blktap-control/nbd810455.6 Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] lock: released /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] lock: closed /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/d202a2b3-97c4-4c19-8c76-14e23278064e Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] lock: closed /var/lock/sm/d202a2b3-97c4-4c19-8c76-14e23278064e/vdi Jul 25 04:59:58 xcp-50-191 SM: [810058][MainThread] lock: closed /var/lock/sm/lvm-b0c68dbe-de25-8245-fa27-0ef4b005d39a/lvchange-p Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31 Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528529.74444459' Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528529.957547669' Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b7eed3d4-6d97-5b57-5ab3-b86682489a31'] Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29 Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:57 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528530.145347496' Jul 25 05:05:57 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528530.297052062' Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fadc805a-3941-dac2-f8e3-83106b30db29'] Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39 Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528530.565184481' Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528530.714325977' Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-9839b298-0487-972f-2eef-509d0193ed39'] Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528530.83176164' Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528530.98965909' Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fb21fe4d-50a2-4d51-675c-31eda858e5fd'] Jul 25 05:05:58 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:58 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799 Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.192494042' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.294038785' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-56190b1a-b74b-6cc6-83c0-ebfe7be57799'] Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8 Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.430949869' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.535702382' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-55abc46b-b138-9296-cf93-e6f74aaa96e8'] Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.648183227' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.758320545' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-b0c68dbe-de25-8245-fa27-0ef4b005d39a'] Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: opening lock file /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache created for VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700 Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.876933322' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] Entering _checkMetadataVolume Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: will initialize now Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] LVMCache: refreshing Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper acquired Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper sent '813225 - 4528531.942798159' Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-e416271b-0547-dcf9-3637-2c153232d700'] Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] pread SUCCESS Jul 25 05:05:59 xcp-50-191 fairlock[3721]: /run/fairlock/devicemapper released Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/bcc61249-53d8-18f8-8437-602316983e1c/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/b7eed3d4-6d97-5b57-5ab3-b86682489a31/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/fadc805a-3941-dac2-f8e3-83106b30db29/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/9839b298-0487-972f-2eef-509d0193ed39/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/fb21fe4d-50a2-4d51-675c-31eda858e5fd/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/56190b1a-b74b-6cc6-83c0-ebfe7be57799/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/55abc46b-b138-9296-cf93-e6f74aaa96e8/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/b0c68dbe-de25-8245-fa27-0ef4b005d39a/sr Jul 25 05:05:59 xcp-50-191 SM: [813225][MainThread] lock: closed /var/lock/sm/e416271b-0547-dcf9-3637-2c153232d700/sr