Oct 12 03:31:36 xcp-hp-31x1 SM: [2111919][MainThread] Setting LVM_DEVICE to /dev/disk/by-scsid/36e843b66265616336343931386565313
Oct 12 03:31:36 xcp-hp-31x1 SM: [2111919][MainThread] Setting LVM_DEVICE to /dev/disk/by-scsid/36e843b66265616336343931386565313
Oct 12 03:31:36 xcp-hp-31x1 SM: [2111919][MainThread] lock: opening lock file /var/lock/sm/26d9572f-01c1-9cfd-79de-ca96eab601cf/sr
Oct 12 03:31:36 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache created for VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf
Oct 12 03:31:36 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/vgs', '--readonly', 'VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:36 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:36 xcp-hp-31x1 SM: [2111919][MainThread] Failed to lock /var/lock/sm/26d9572f-01c1-9cfd-79de-ca96eab601cf/sr on first attempt, blocked by PID 1055144
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/26d9572f-01c1-9cfd-79de-ca96eab601cf/sr
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: will initialize now
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: refreshing
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/26d9572f-01c1-9cfd-79de-ca96eab601cf/sr
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] Entering _checkMetadataVolume
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/26d9572f-01c1-9cfd-79de-ca96eab601cf/sr
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] vdi_delete {'host_ref': 'OpaqueRef:68a12fa2-55f6-6f4e-1c22-8a1bacc8c62e', 'command': 'vdi_delete', 'args': [], 'device_config': {'SRmaster': 'true', 'target': '172.16.150.2', 'targetIQN': 'iqn.2004-04.com.qnap:ts-h2483xu-rp:iscsi.70t.7cb92b', 'SCSIid': '36e843b66265616336343931386565313'}, 'session_ref': '******', 'sr_ref': 'OpaqueRef:8b79d511-1b68-6dfc-8e36-0333486985bc', 'sr_uuid': '26d9572f-01c1-9cfd-79de-ca96eab601cf', 'vdi_ref': 'OpaqueRef:6683789f-f26c-35bd-530d-1bea09349719', 'vdi_location': '2f629c79-d2ba-48ee-978f-04944a2e9945', 'vdi_uuid': '2f629c79-d2ba-48ee-978f-04944a2e9945', 'subtask_of': 'DummyRef:|a06a7c7f-dbad-9a96-320f-619874b0ff0c|VDI.destroy', 'vdi_on_boot': 'persist', 'vdi_allow_caching': 'false', 'local_cache_sr': '9253ed78-6823-d978-6db8-c7cae3dc8f59'}
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] LVMVDI.delete for 2f629c79-d2ba-48ee-978f-04944a2e9945
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] Raising exception [46, The VDI is not available [opterr=LV not found]]
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] lock: opening lock file /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: refreshing
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:50 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/usr/sbin/cbt-util', 'get', '-n', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog', '-p']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-an', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--26d9572f--01c1--9cfd--79de--ca96eab601cf-2f629c79--d2ba--48ee--978f--04944a2e9945.cbtlog']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: refreshing
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/usr/sbin/cbt-util', 'get', '-n', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog', '-c']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-an', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--26d9572f--01c1--9cfd--79de--ca96eab601cf-2f629c79--d2ba--48ee--978f--04944a2e9945.cbtlog']
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:51 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] lock: opening lock file /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: refreshing
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog']
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] ['/usr/sbin/cbt-util', 'set', '-n', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog', '-p', '00000000-0000-0000-0000-000000000000']
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-an', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog']
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:52 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--26d9572f--01c1--9cfd--79de--ca96eab601cf-2f546ede--efcb--4ccf--8c23--2ea5a5e0d01f.cbtlog']
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: refreshing
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog']
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] ['/usr/sbin/cbt-util', 'get', '-n', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog', '-f']
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-an', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog']
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--26d9572f--01c1--9cfd--79de--ca96eab601cf-2f546ede--efcb--4ccf--8c23--2ea5a5e0d01f.cbtlog']
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] Pause request for 2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f
Oct 12 03:31:53 xcp-hp-31x1 SM: [2111919][MainThread] Calling tap-pause on host OpaqueRef:b90d66df-047a-648c-8688-2a8f90b265ee
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: refreshing
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog']
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] LVMCache: refreshing
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf']
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog']
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] ['/usr/sbin/cbt-util', 'coalesce', '-p', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog', '-c', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog']
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:56 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvchange', '-an', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f.cbtlog']
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--26d9572f--01c1--9cfd--79de--ca96eab601cf-2f546ede--efcb--4ccf--8c23--2ea5a5e0d01f.cbtlog']
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread] Unpause request for 2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f secondary=None
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread] Calling tap-unpause on host OpaqueRef:b90d66df-047a-648c-8688-2a8f90b265ee
Oct 12 03:31:57 xcp-hp-31x1 SM: [2111919][MainThread] lock: acquired /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/lvremove', '-f', '/dev/VG_XenStorage-26d9572f-01c1-9cfd-79de-ca96eab601cf/2f629c79-d2ba-48ee-978f-04944a2e9945.cbtlog']
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] ['/sbin/dmsetup', 'status', 'VG_XenStorage--26d9572f--01c1--9cfd--79de--ca96eab601cf-2f629c79--d2ba--48ee--978f--04944a2e9945.cbtlog']
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread]   pread SUCCESS
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] lock: unlinking lock file /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] lock: closed /var/lock/sm/2f629c79-d2ba-48ee-978f-04944a2e9945/cbtlog
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] lock: released /var/lock/sm/26d9572f-01c1-9cfd-79de-ca96eab601cf/sr
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] lock: closed /var/lock/sm/26d9572f-01c1-9cfd-79de-ca96eab601cf/sr
Oct 12 03:31:58 xcp-hp-31x1 SM: [2111919][MainThread] lock: closed /var/lock/sm/2f546ede-efcb-4ccf-8c23-2ea5a5e0d01f/cbtlog