Jump to content
Welcome to our new Citrix community!
  • 0

The VDI is not available. Storage scan fail.


Yongho Kim

Question

Hello, I hava a problem about XenServer, VDI

 

product name : XenServer

Versions : 6.2 

Storage : 3 ea

 

Local Stroage 1 : OK (/dev/sda)

Local Storage 2 : ERROR (/dev/sdb)

Local Storage 3 : OK (/dev/sdc)

 

I setup new disk(Local Storage3) last week. fdisk -l result is blow

image.thumb.png.80fe3ec28c4ef22cc67e595dcff194bc.pngimage.thumb.png.5bee614d17814e96ec5408002e55876d.png

(/dev/sdc need to partioning??)

 

After setup disk, I executed commands in xenserver console.

1. # xe host-list

2. # xe sr-create host-uuid=host-uuid content-type=user name-label="Local storage3" shared=false device-config:device=/dev/sdc type=lvm

history

 1038  xe host-list
 1039  fdisk -l
 1040  xe sr-create host-uuid=427243ae-90ab-4c04-967c-34192045e709 content-type=user name-label="Local storage3" shared=false device-config:device=/dev/sdc type=lvm
 1041  clear
 1042  clea
 1043  clear
 1044  fdisk -l
 1045  clear
 1046  fstab
 1047  clear
 1048  fdisk -l
 1049  clear
 1050  cat /etc/fstab
 1051  clear
 1052  clear
 1053  pvdisplay
 1054  clear
 1055  xe vm-list
 1056  clear
 1057  pvdisplay
 1058  pvdisplay
 1059  clear
 1060  lvscan
 1061  clear
 1062  lvscan
 1063  clear
 1064  cat /proc/partition
 1065  cat /proc/partitions
 1066  clear
 1067  cd /
 1068  clear
 1069  ls -al
 1070  clear
 1071  fdisk -l
 1072  clear
 1073  vgdisplay
 1074  clear
 1075  xe vdi-list
 1076  clear
 1077  xe vdi-list | grep name-label
 1078  clear
...

After that, I can't run my VMs vdi is in Local Storage2. It shows me to error message "The VDI is not available" and Local Storage2 scan failed. (The specified storage repository scan failed)

Local Storage2's whole VM is dead.

 

How can I fix it?? or What more information do you need?

 

Please help. Please..

 

 

Link to comment

5 answers to this question

Recommended Posts

  • 0

Yes, I would do the same. Run fsck on /dev/sdb and see if that fixes any filesystem issues. 

Creating another storage on another disk would not have cause this issue.  You could

be having a hard drive failure. Does /var/log/SMlog give any indication ? And of course

I'm going to nag you about being on an ancient version.

 

--Alan--

 

 

Link to comment
  • 0
11 hours ago, Alan Lantz said:

Yes, I would do the same. Run fsck on /dev/sdb and see if that fixes any filesystem issues. 

Creating another storage on another disk would not have cause this issue.  You could

be having a hard drive failure. Does /var/log/SMlog give any indication ? And of course

I'm going to nag you about being on an ancient version.

 

--Alan--

 

 

 

I checked /var/log/SMlog file but I don't know what to check..

Is this problem can solve?? I want to know this.

 

/var/log/SMlog file. (1400 lines, I setup new disk on 10/18)

Oct 30 17:28:32 bi-first-server SM: [21980] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:32 bi-first-server SM: [21980] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:32 bi-first-server SM: [21980] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:32 bi-first-server SM: [21980] '
Oct 30 17:28:32 bi-first-server SM: [21980] LVMCache: will initialize now
Oct 30 17:28:32 bi-first-server SM: [21980] LVMCache: refreshing
Oct 30 17:28:32 bi-first-server SM: [21980] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:33 bi-first-server SM: [21980] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:33 bi-first-server SM: [21980]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:33 bi-first-server SM: [21980] '
Oct 30 17:28:33 bi-first-server SM: [21980] vdi_epoch_begin {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|b5b60bff-fb7b-f6fa-586c-54cd063946b4|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:6b454cbf-eed4-dcdf-9319-8388bbfaa00c', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '62ca648a-9808-41bf-a30e-50903a43507e', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:e53b8829-6ced-2858-2bda-3be3d0a6fb84', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '62ca648a-9808-41bf-a30e-50903a43507e'}
Oct 30 17:28:33 bi-first-server SM: [21980] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 30 17:28:33 bi-first-server SM: [21995] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:33 bi-first-server SM: [21995] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:33 bi-first-server SM: [21995] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:33 bi-first-server SM: [21995] '
Oct 30 17:28:33 bi-first-server SM: [21995] LVMCache: will initialize now
Oct 30 17:28:33 bi-first-server SM: [21995] LVMCache: refreshing
Oct 30 17:28:33 bi-first-server SM: [21995] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:33 bi-first-server SM: [21995] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:33 bi-first-server SM: [21995]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:33 bi-first-server SM: [21995] '
Oct 30 17:28:33 bi-first-server SM: [21995] vdi_attach {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|b61ffb5d-117d-554c-655a-398ad690f1e2|VDI.attach', 'vdi_ref': 'OpaqueRef:6b454cbf-eed4-dcdf-9319-8388bbfaa00c', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '62ca648a-9808-41bf-a30e-50903a43507e', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:33496edb-0cc7-edc0-e724-46e235667a32', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '62ca648a-9808-41bf-a30e-50903a43507e'}
Oct 30 17:28:33 bi-first-server SM: [21995] result: {'params': '/dev/sm/backend/bb66583f-2842-f789-6bc7-527355ce2044/62ca648a-9808-41bf-a30e-50903a43507e', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEjYyY2E2NDhhLTk4MDgtNDEgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIDYyY2E2NDhhLTk4MDgtNDFiZi1hMzBlLTUwOTAzYTQzNTA3ZSA=', 'vdi-uuid': '62ca648a-9808-41bf-a30e-50903a43507e', 'mem-pool': 'bb66583f-2842-f789-6bc7-527355ce2044'}}
Oct 30 17:28:33 bi-first-server SM: [21995] lock: closed /var/lock/sm/62ca648a-9808-41bf-a30e-50903a43507e/vdi
Oct 30 17:28:33 bi-first-server SM: [21995] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 30 17:28:33 bi-first-server SM: [22027] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:33 bi-first-server SM: [22027] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:33 bi-first-server SM: [22027] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:33 bi-first-server SM: [22027] '
Oct 30 17:28:33 bi-first-server SM: [22027] LVMCache: will initialize now
Oct 30 17:28:33 bi-first-server SM: [22027] LVMCache: refreshing
Oct 30 17:28:33 bi-first-server SM: [22027] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:33 bi-first-server SM: [22027] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:33 bi-first-server SM: [22027]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:33 bi-first-server SM: [22027] '
Oct 30 17:28:33 bi-first-server SM: [22027] vdi_activate {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|2b50f027-15a4-d5e9-f228-c50d1c7cb4f9|VDI.activate', 'vdi_ref': 'OpaqueRef:6b454cbf-eed4-dcdf-9319-8388bbfaa00c', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '62ca648a-9808-41bf-a30e-50903a43507e', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:728bd5ea-209b-db17-e6b8-fd486f99aedd', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '62ca648a-9808-41bf-a30e-50903a43507e'}
Oct 30 17:28:33 bi-first-server SM: [22027] blktap2.activate
Oct 30 17:28:33 bi-first-server SM: [22027] lock: acquired /var/lock/sm/62ca648a-9808-41bf-a30e-50903a43507e/vdi
Oct 30 17:28:33 bi-first-server SM: [22027] Adding tag to: 62ca648a-9808-41bf-a30e-50903a43507e
Oct 30 17:28:33 bi-first-server SM: [22027] Activate lock succeeded
Oct 30 17:28:33 bi-first-server SM: [22027] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:33 bi-first-server SM: [22027] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:33 bi-first-server SM: [22027] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:33 bi-first-server SM: [22027] '
Oct 30 17:28:33 bi-first-server SM: [22027] LVMCache: will initialize now
Oct 30 17:28:33 bi-first-server SM: [22027] LVMCache: refreshing
Oct 30 17:28:33 bi-first-server SM: [22027] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:34 bi-first-server SM: [22027] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:34 bi-first-server SM: [22027]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:34 bi-first-server SM: [22027] '
Oct 30 17:28:34 bi-first-server SM: [22027] LVHDVDI.attach for 62ca648a-9808-41bf-a30e-50903a43507e
Oct 30 17:28:34 bi-first-server SM: [22027] LVMCache: will initialize now
Oct 30 17:28:34 bi-first-server SM: [22027] LVMCache: refreshing
Oct 30 17:28:34 bi-first-server SM: [22027] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:34 bi-first-server SM: [22027] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:34 bi-first-server SM: [22027]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:34 bi-first-server SM: [22027] '
Oct 30 17:28:34 bi-first-server SM: [22027] Exception in activate/attach
Oct 30 17:28:34 bi-first-server SM: [22027] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for 62ca648a-9808-41bf-a30e-50903a43507e
Oct 30 17:28:34 bi-first-server SM: [22027] ***** BLKTAP2:<function _activate_locked at 0x9f6317c>: EXCEPTION util.CommandException, 5
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/blktap2.py", line 52, in wrapper
Oct 30 17:28:34 bi-first-server SM: [22027]     ret = op(self, *args)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/blktap2.py", line 1424, in _activate_locked
Oct 30 17:28:34 bi-first-server SM: [22027]     self._attach(sr_uuid, vdi_uuid)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/blktap2.py", line 1456, in _attach
Oct 30 17:28:34 bi-first-server SM: [22027]     attach_info = xmlrpclib.loads(self.target.attach(sr_uuid, vdi_uuid))[0][0]
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/blktap2.py", line 1012, in attach
Oct 30 17:28:34 bi-first-server SM: [22027]     return self.vdi.attach(sr_uuid, vdi_uuid)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/LVHDSR.py", line 1314, in attach
Oct 30 17:28:34 bi-first-server SM: [22027]     if self.sr.journaler.hasJournals(self.uuid):
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/journaler.py", line 70, in hasJournals
Oct 30 17:28:34 bi-first-server SM: [22027]     entries = self._getAllEntries()
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/journaler.py", line 80, in _getAllEntries
Oct 30 17:28:34 bi-first-server SM: [22027]     lvList = self.lvmCache.getTagged(self.LV_TAG)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/lvmcache.py", line 41, in wrapper
Oct 30 17:28:34 bi-first-server SM: [22027]     self.refresh()
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/lvmcache.py", line 71, in refresh
Oct 30 17:28:34 bi-first-server SM: [22027]     text = util.pread2(cmd)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/util.py", line 181, in pread2
Oct 30 17:28:34 bi-first-server SM: [22027]     return pread(cmdlist, quiet = quiet)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/util.py", line 174, in pread
Oct 30 17:28:34 bi-first-server SM: [22027]     raise CommandException(rc, str(cmdlist), stderr.strip())
Oct 30 17:28:34 bi-first-server SM: [22027] 
Oct 30 17:28:34 bi-first-server SM: [22027] Raising exception [46, The VDI is not available [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:34 bi-first-server SM: [22027]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]]
Oct 30 17:28:34 bi-first-server SM: [22027] lock: released /var/lock/sm/62ca648a-9808-41bf-a30e-50903a43507e/vdi
Oct 30 17:28:34 bi-first-server SM: [22027] ***** generic exception: vdi_activate: EXCEPTION SR.SROSError, The VDI is not available [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:34 bi-first-server SM: [22027]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/SRCommand.py", line 106, in run
Oct 30 17:28:34 bi-first-server SM: [22027]     return self._run_locked(sr)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/SRCommand.py", line 153, in _run_locked
Oct 30 17:28:34 bi-first-server SM: [22027]     return self._run(sr, target)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/SRCommand.py", line 246, in _run
Oct 30 17:28:34 bi-first-server SM: [22027]     writable, caching_params)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/blktap2.py", line 1395, in activate
Oct 30 17:28:34 bi-first-server SM: [22027]     writable, caching_params):
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/blktap2.py", line 60, in wrapper
Oct 30 17:28:34 bi-first-server SM: [22027]     raise xs_errors.XenError(excType, opterr=msg)
Oct 30 17:28:34 bi-first-server SM: [22027]   File "/opt/xensource/sm/xs_errors.py", line 49, in __init__
Oct 30 17:28:34 bi-first-server SM: [22027]     raise SR.SROSError(errorcode, errormessage)
Oct 30 17:28:34 bi-first-server SM: [22027] 
Oct 30 17:28:34 bi-first-server SM: [22027] lock: closed /var/lock/sm/62ca648a-9808-41bf-a30e-50903a43507e/vdi
Oct 30 17:28:34 bi-first-server SM: [22027] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 30 17:28:34 bi-first-server SM: [22027] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 30 17:28:34 bi-first-server SM: [22071] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:34 bi-first-server SM: [22071] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:34 bi-first-server SM: [22071] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:34 bi-first-server SM: [22071] '
Oct 30 17:28:34 bi-first-server SM: [22071] LVMCache: will initialize now
Oct 30 17:28:34 bi-first-server SM: [22071] LVMCache: refreshing
Oct 30 17:28:34 bi-first-server SM: [22071] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:28:34 bi-first-server SM: [22071] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:28:34 bi-first-server SM: [22071]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:28:34 bi-first-server SM: [22071] '
Oct 30 17:28:34 bi-first-server SM: [22071] vdi_detach {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|f4fb7717-87b5-1423-cc3a-944171978fd0|VDI.detach', 'vdi_ref': 'OpaqueRef:6b454cbf-eed4-dcdf-9319-8388bbfaa00c', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '62ca648a-9808-41bf-a30e-50903a43507e', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:a904ed19-f505-8125-e008-c654708ab535', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '62ca648a-9808-41bf-a30e-50903a43507e'}
Oct 30 17:28:34 bi-first-server SM: [22071] lock: closed /var/lock/sm/62ca648a-9808-41bf-a30e-50903a43507e/vdi
Oct 30 17:28:34 bi-first-server SM: [22071] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 30 17:39:01 bi-first-server SM: [25241] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:39:01 bi-first-server SM: [25241] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:39:01 bi-first-server SM: [25241] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:39:01 bi-first-server SM: [25241] '
Oct 30 17:39:01 bi-first-server SM: [25241] LVMCache: will initialize now
Oct 30 17:39:01 bi-first-server SM: [25241] LVMCache: refreshing
Oct 30 17:39:01 bi-first-server SM: [25241] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:39:01 bi-first-server SM: [25241] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:39:01 bi-first-server SM: [25241]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:39:01 bi-first-server SM: [25241] '
Oct 30 17:39:01 bi-first-server SM: [25241] lock: acquired /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 30 17:39:01 bi-first-server SM: [25241] sr_scan {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|94e3e55e-1ad4-8a73-8204-d0e78ee4e6f6|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:477fe9d9-461e-55e0-520a-85749ca9268c', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73'}
Oct 30 17:39:01 bi-first-server SM: [25241] LVHDSR.scan for bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:39:01 bi-first-server SM: [25241] LVMCache: refreshing
Oct 30 17:39:01 bi-first-server SM: [25241] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 30 17:39:01 bi-first-server SM: [25241] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:39:01 bi-first-server SM: [25241]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 30 17:39:01 bi-first-server SM: [25241] '
Oct 30 17:39:01 bi-first-server SM: [25241] lock: released /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 30 17:39:01 bi-first-server SM: [25241] ***** sr_scan: EXCEPTION util.CommandException, 5
Oct 30 17:39:01 bi-first-server SM: [25241]   File "/opt/xensource/sm/SRCommand.py", line 106, in run
Oct 30 17:39:01 bi-first-server SM: [25241]     return self._run_locked(sr)
Oct 30 17:39:01 bi-first-server SM: [25241]   File "/opt/xensource/sm/SRCommand.py", line 153, in _run_locked
Oct 30 17:39:01 bi-first-server SM: [25241]     return self._run(sr, target)
Oct 30 17:39:01 bi-first-server SM: [25241]   File "/opt/xensource/sm/SRCommand.py", line 313, in _run
Oct 30 17:39:01 bi-first-server SM: [25241]     return sr.scan(self.params['sr_uuid'])
Oct 30 17:39:01 bi-first-server SM: [25241]   File "/opt/xensource/sm/LVMSR", line 607, in scan
Oct 30 17:39:01 bi-first-server SM: [25241]     self.lvmCache.refresh()
Oct 30 17:39:01 bi-first-server SM: [25241]   File "/opt/xensource/sm/lvmcache.py", line 71, in refresh
Oct 30 17:39:01 bi-first-server SM: [25241]     text = util.pread2(cmd)
Oct 30 17:39:01 bi-first-server SM: [25241]   File "/opt/xensource/sm/util.py", line 181, in pread2
Oct 30 17:39:01 bi-first-server SM: [25241]     return pread(cmdlist, quiet = quiet)
Oct 30 17:39:01 bi-first-server SM: [25241]   File "/opt/xensource/sm/util.py", line 174, in pread
Oct 30 17:39:01 bi-first-server SM: [25241]     raise CommandException(rc, str(cmdlist), stderr.strip())
Oct 30 17:39:01 bi-first-server SM: [25241] 
Oct 30 17:39:01 bi-first-server SM: [25241] Raising exception [40, The SR scan failed  [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 30 17:39:01 bi-first-server SM: [25241]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]]
Oct 30 17:39:01 bi-first-server SM: [25241] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 11:23:00 bi-first-server SM: [16088] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 11:23:00 bi-first-server SM: [16088] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 11:23:00 bi-first-server SM: [16088] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 11:23:00 bi-first-server SM: [16088] '
Oct 31 11:23:00 bi-first-server SM: [16088] LVMCache: will initialize now
Oct 31 11:23:00 bi-first-server SM: [16088] LVMCache: refreshing
Oct 31 11:23:00 bi-first-server SM: [16088] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 11:23:01 bi-first-server SM: [16088] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 11:23:01 bi-first-server SM: [16088]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 11:23:01 bi-first-server SM: [16088] '
Oct 31 11:23:01 bi-first-server SM: [16088] vdi_epoch_begin {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|593eeff7-2671-7829-a9f3-33265d6d5fc4|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:a2693750-cccd-2638-3a4f-82f1757860df', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '79a6b627-6a67-4dfb-bf6f-8fea8b45489c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:821d6a65-ed85-b491-3607-89d9ab97e2c4', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '79a6b627-6a67-4dfb-bf6f-8fea8b45489c'}
Oct 31 11:23:01 bi-first-server SM: [16088] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:18 bi-first-server SM: [17063] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:18 bi-first-server SM: [17063] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:18 bi-first-server SM: [17063] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:18 bi-first-server SM: [17063] '
Oct 31 15:05:18 bi-first-server SM: [17063] LVMCache: will initialize now
Oct 31 15:05:18 bi-first-server SM: [17063] LVMCache: refreshing
Oct 31 15:05:18 bi-first-server SM: [17063] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:18 bi-first-server SM: [17063] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:18 bi-first-server SM: [17063]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:18 bi-first-server SM: [17063] '
Oct 31 15:05:18 bi-first-server SM: [17063] vdi_epoch_begin {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|98a47e91-f7ab-8e47-83df-1a9d088197b5|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:a2693750-cccd-2638-3a4f-82f1757860df', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '79a6b627-6a67-4dfb-bf6f-8fea8b45489c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:5d3f294c-3cd6-de09-cd8c-9ba463704e9b', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '79a6b627-6a67-4dfb-bf6f-8fea8b45489c'}
Oct 31 15:05:18 bi-first-server SM: [17063] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:28 bi-first-server SM: [17238] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:28 bi-first-server SM: [17238] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17238]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17238] lock: acquired /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:29 bi-first-server SM: [17238] LVMCache: will initialize now
Oct 31 15:05:29 bi-first-server SM: [17238] LVMCache: refreshing
Oct 31 15:05:29 bi-first-server SM: [17238] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17238]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17238] lock: released /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:29 bi-first-server SM: [17238] Entering _checkMetadataVolume
Oct 31 15:05:29 bi-first-server SM: [17238] vdi_epoch_begin {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|7abc6e3a-8d4e-2001-4543-265a298365b9|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:1257b946-3889-f94e-1ef9-82a2bb0d24c4', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:ac74ea81-e6d4-638e-6f43-0ca12c5fc0d8', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d'}
Oct 31 15:05:29 bi-first-server SM: [17238] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:29 bi-first-server SM: [17253] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:29 bi-first-server SM: [17253] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17253]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17253] Entering _checkMetadataVolume
Oct 31 15:05:29 bi-first-server SM: [17253] LVMCache: will initialize now
Oct 31 15:05:29 bi-first-server SM: [17253] LVMCache: refreshing
Oct 31 15:05:29 bi-first-server SM: [17253] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17253]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17253] vdi_attach {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|f24ed75f-80bd-2d47-cc47-6d0ce4aabb76|VDI.attach', 'vdi_ref': 'OpaqueRef:1257b946-3889-f94e-1ef9-82a2bb0d24c4', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:b7322b41-4cf9-6f2d-6f63-141d878ac95c', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d'}
Oct 31 15:05:29 bi-first-server SM: [17253] result: {'params': '/dev/sm/backend/d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEmE3YWRjYjMwLTViMTQtNGQgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIGE3YWRjYjMwLTViMTQtNGRjZS04YmMxLTdmMDI4YTkwOGMyZCA=', 'vdi-uuid': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'mem-pool': 'd87cdd6c-d520-1250-637c-a3f42c8da868'}}
Oct 31 15:05:29 bi-first-server SM: [17253] lock: closed /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:29 bi-first-server SM: [17253] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:29 bi-first-server SM: [17273] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17273]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17273] Entering _checkMetadataVolume
Oct 31 15:05:29 bi-first-server SM: [17273] LVMCache: will initialize now
Oct 31 15:05:29 bi-first-server SM: [17273] LVMCache: refreshing
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17273]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17273] vdi_activate {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|d921134b-a4c6-8ae5-a904-c4bae08ee3b7|VDI.activate', 'vdi_ref': 'OpaqueRef:1257b946-3889-f94e-1ef9-82a2bb0d24c4', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:8da73256-7d3f-a009-1356-5b02b5055be8', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d'}
Oct 31 15:05:29 bi-first-server SM: [17273] blktap2.activate
Oct 31 15:05:29 bi-first-server SM: [17273] lock: acquired /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:29 bi-first-server SM: [17273] Adding tag to: a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] Activate lock succeeded
Oct 31 15:05:29 bi-first-server SM: [17273] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17273]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17273] Entering _checkMetadataVolume
Oct 31 15:05:29 bi-first-server SM: [17273] LVMCache: will initialize now
Oct 31 15:05:29 bi-first-server SM: [17273] LVMCache: refreshing
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:29 bi-first-server SM: [17273]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17273] LVHDVDI.attach for a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d (0, 0) + (1, 0) => (1, 0)
Oct 31 15:05:29 bi-first-server SM: [17273] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d set => (1, 0b)
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/lvchange', '-ay', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d']
Oct 31 15:05:29 bi-first-server SM: [17273]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17273] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d']
Oct 31 15:05:29 bi-first-server SM: [17273]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17273] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d', '-l', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868', '-a']
Oct 31 15:05:29 bi-first-server SM: [17273]   pread SUCCESS
Oct 31 15:05:29 bi-first-server SM: [17273] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d (1, 0) + (0, 1) => (1, 1)
Oct 31 15:05:29 bi-first-server SM: [17273] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d set => (1, 1b)
Oct 31 15:05:29 bi-first-server SM: [17273] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:05:29 bi-first-server SM: [17273] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d set => (0, 1b)
Oct 31 15:05:29 bi-first-server SM: [17273] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] PhyLink(/dev/sm/phy/d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d) -> /dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/tap-ctl', 'allocate']
Oct 31 15:05:29 bi-first-server SM: [17273]  = 0
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/tap-ctl', 'spawn']
Oct 31 15:05:29 bi-first-server SM: [17273]  = 0
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/tap-ctl', 'attach', '-p', '17324', '-m', '20']
Oct 31 15:05:29 bi-first-server SM: [17273]  = 0
Oct 31 15:05:29 bi-first-server SM: [17273] ['/usr/sbin/tap-ctl', 'open', '-p', '17324', '-m', '20', '-a', 'vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d']
Oct 31 15:05:29 bi-first-server SM: [17273]  = 0
Oct 31 15:05:29 bi-first-server SM: [17273] tap.activate: Launched Tapdisk(vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d, pid=17324, minor=20, state=R)
Oct 31 15:05:29 bi-first-server SM: [17273] DeviceNode(/dev/sm/backend/d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d) -> /dev/xen/blktap-2/tapdev20
Oct 31 15:05:29 bi-first-server SM: [17273] lock: released /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:29 bi-first-server SM: [17273] lock: closed /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:29 bi-first-server SM: [17273] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:29 bi-first-server SM: [17273] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:35 bi-first-server SM: [17747] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:35 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:35 bi-first-server SM: [17747] Entering _checkMetadataVolume
Oct 31 15:05:35 bi-first-server SM: [17747] LVMCache: will initialize now
Oct 31 15:05:35 bi-first-server SM: [17747] LVMCache: refreshing
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:35 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:35 bi-first-server SM: [17747] vdi_deactivate {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|fad72607-e894-4208-00b3-f490c711bc9d|VDI.deactivate', 'vdi_ref': 'OpaqueRef:1257b946-3889-f94e-1ef9-82a2bb0d24c4', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:68f14308-1c08-37c6-5516-a563b3544c02', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_deactivate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d'}
Oct 31 15:05:35 bi-first-server SM: [17747] blktap2.deactivate
Oct 31 15:05:35 bi-first-server SM: [17747] lock: acquired /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/tap-ctl', 'close', '-p', '17324', '-m', '20']
Oct 31 15:05:35 bi-first-server SM: [17747]  = 0
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/tap-ctl', 'detach', '-p', '17324', '-m', '20']
Oct 31 15:05:35 bi-first-server SM: [17747]  = 0
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/tap-ctl', 'free', '-m', '20']
Oct 31 15:05:35 bi-first-server SM: [17747]  = 0
Oct 31 15:05:35 bi-first-server SM: [17747] tap.deactivate: Shut down Tapdisk(vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d, pid=17324, minor=20, state=R)
Oct 31 15:05:35 bi-first-server SM: [17747] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:35 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:35 bi-first-server SM: [17747] Entering _checkMetadataVolume
Oct 31 15:05:35 bi-first-server SM: [17747] LVMCache: will initialize now
Oct 31 15:05:35 bi-first-server SM: [17747] LVMCache: refreshing
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:35 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:35 bi-first-server SM: [17747] LVHDVDI.detach for a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d (0, 1) + (1, 0) => (1, 1)
Oct 31 15:05:35 bi-first-server SM: [17747] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d set => (1, 1b)
Oct 31 15:05:35 bi-first-server SM: [17747] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d']
Oct 31 15:05:35 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:35 bi-first-server SM: [17747] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d', '-l', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868', '-a']
Oct 31 15:05:35 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:35 bi-first-server SM: [17747] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:05:35 bi-first-server SM: [17747] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d set => (0, 1b)
Oct 31 15:05:35 bi-first-server SM: [17747] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:35 bi-first-server SM: [17747] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d (0, 1) + (0, -1) => (0, 0)
Oct 31 15:05:35 bi-first-server SM: [17747] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:a7adcb30-5b14-4dce-8bc1-7f028a908c2d set => (0, 0b)
Oct 31 15:05:35 bi-first-server SM: [17747] ['/usr/sbin/lvchange', '-an', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-a7adcb30-5b14-4dce-8bc1-7f028a908c2d']
Oct 31 15:05:36 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:36 bi-first-server SM: [17747] ['/sbin/dmsetup', 'status', 'VG_XenStorage--d87cdd6c--d520--1250--637c--a3f42c8da868-VHD--a7adcb30--5b14--4dce--8bc1--7f028a908c2d']
Oct 31 15:05:36 bi-first-server SM: [17747]   pread SUCCESS
Oct 31 15:05:36 bi-first-server SM: [17747] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:36 bi-first-server SM: [17747] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:36 bi-first-server SM: [17747] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for a7adcb30-5b14-4dce-8bc1-7f028a908c2d
Oct 31 15:05:36 bi-first-server SM: [17747] lock: released /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:36 bi-first-server SM: [17747] lock: closed /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:36 bi-first-server SM: [17747] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:36 bi-first-server SM: [17747] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:36 bi-first-server SM: [17830] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:36 bi-first-server SM: [17830] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:36 bi-first-server SM: [17830]   pread SUCCESS
Oct 31 15:05:36 bi-first-server SM: [17830] Entering _checkMetadataVolume
Oct 31 15:05:36 bi-first-server SM: [17830] LVMCache: will initialize now
Oct 31 15:05:36 bi-first-server SM: [17830] LVMCache: refreshing
Oct 31 15:05:36 bi-first-server SM: [17830] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:36 bi-first-server SM: [17854] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:36 bi-first-server SM: [17830]   pread SUCCESS
Oct 31 15:05:36 bi-first-server SM: [17854] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:36 bi-first-server SM: [17830] vdi_detach {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|fe1eece6-4782-de60-4682-c504f8191032|VDI.detach', 'vdi_ref': 'OpaqueRef:1257b946-3889-f94e-1ef9-82a2bb0d24c4', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:77794703-2323-f6d7-7f3f-e87b36c07dd0', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d'}
Oct 31 15:05:36 bi-first-server SM: [17830] lock: closed /var/lock/sm/a7adcb30-5b14-4dce-8bc1-7f028a908c2d/vdi
Oct 31 15:05:36 bi-first-server SM: [17830] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:36 bi-first-server SM: [17854] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:36 bi-first-server SM: [17854] '
Oct 31 15:05:36 bi-first-server SM: [17854] LVMCache: will initialize now
Oct 31 15:05:36 bi-first-server SM: [17854] LVMCache: refreshing
Oct 31 15:05:36 bi-first-server SM: [17854] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:36 bi-first-server SM: [17854] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:36 bi-first-server SM: [17854]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:36 bi-first-server SM: [17854] '
Oct 31 15:05:36 bi-first-server SM: [17854] vdi_epoch_begin {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|8e629b1c-599e-81df-6477-55bec5ee9f71|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:2b27a1d7-5d63-d096-8a35-7562296a1f14', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '9d1e0648-3ca4-4e18-a5b8-626b2a131084', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:02516f7c-365a-4429-46d5-b0375a755d4f', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '9d1e0648-3ca4-4e18-a5b8-626b2a131084'}
Oct 31 15:05:36 bi-first-server SM: [17854] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:36 bi-first-server SM: [17901] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:36 bi-first-server SM: [17901] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:36 bi-first-server SM: [17901] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:36 bi-first-server SM: [17901] '
Oct 31 15:05:36 bi-first-server SM: [17901] LVMCache: will initialize now
Oct 31 15:05:36 bi-first-server SM: [17901] LVMCache: refreshing
Oct 31 15:05:36 bi-first-server SM: [17901] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:36 bi-first-server SM: [17901] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:36 bi-first-server SM: [17901]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:36 bi-first-server SM: [17901] '
Oct 31 15:05:36 bi-first-server SM: [17901] vdi_epoch_begin {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|6e1d61d4-fa55-1f25-42f6-01d9ec88b10e|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:4441dc6a-fcfb-d0e5-2517-9bf1c2f70997', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '38b7bd0a-d163-48a0-8e9d-09552f80ff9e', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:ad3eaacb-2a21-c112-e97b-32555465b9be', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '38b7bd0a-d163-48a0-8e9d-09552f80ff9e'}
Oct 31 15:05:36 bi-first-server SM: [17901] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:36 bi-first-server SM: [17927] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:36 bi-first-server SM: [17927] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:36 bi-first-server SM: [17927] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:36 bi-first-server SM: [17927] '
Oct 31 15:05:36 bi-first-server SM: [17927] LVMCache: will initialize now
Oct 31 15:05:36 bi-first-server SM: [17927] LVMCache: refreshing
Oct 31 15:05:36 bi-first-server SM: [17927] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:36 bi-first-server SM: [17927] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:36 bi-first-server SM: [17927]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:36 bi-first-server SM: [17927] '
Oct 31 15:05:36 bi-first-server SM: [17927] vdi_attach {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|5cd1ca8d-66cd-5211-fd02-891290a7031d|VDI.attach', 'vdi_ref': 'OpaqueRef:2b27a1d7-5d63-d096-8a35-7562296a1f14', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '9d1e0648-3ca4-4e18-a5b8-626b2a131084', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:6128ec71-495d-72b2-c5a1-1c5fc4a4599c', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '9d1e0648-3ca4-4e18-a5b8-626b2a131084'}
Oct 31 15:05:36 bi-first-server SM: [17927] result: {'params': '/dev/sm/backend/bb66583f-2842-f789-6bc7-527355ce2044/9d1e0648-3ca4-4e18-a5b8-626b2a131084', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEjlkMWUwNjQ4LTNjYTQtNGUgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIDlkMWUwNjQ4LTNjYTQtNGUxOC1hNWI4LTYyNmIyYTEzMTA4NCA=', 'vdi-uuid': '9d1e0648-3ca4-4e18-a5b8-626b2a131084', 'mem-pool': 'bb66583f-2842-f789-6bc7-527355ce2044'}}
Oct 31 15:05:36 bi-first-server SM: [17927] lock: closed /var/lock/sm/9d1e0648-3ca4-4e18-a5b8-626b2a131084/vdi
Oct 31 15:05:36 bi-first-server SM: [17927] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:37 bi-first-server SM: [17984] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:37 bi-first-server SM: [17984] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [17984] '
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache: will initialize now
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache: refreshing
Oct 31 15:05:37 bi-first-server SM: [17984] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:37 bi-first-server SM: [17984] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [17984]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:37 bi-first-server SM: [17984] '
Oct 31 15:05:37 bi-first-server SM: [17984] vdi_activate {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|83899ef6-dd85-19a3-e71e-511793a99c42|VDI.activate', 'vdi_ref': 'OpaqueRef:2b27a1d7-5d63-d096-8a35-7562296a1f14', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '9d1e0648-3ca4-4e18-a5b8-626b2a131084', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:2cbae220-a88e-b6fa-2712-50f17f0d7795', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '9d1e0648-3ca4-4e18-a5b8-626b2a131084'}
Oct 31 15:05:37 bi-first-server SM: [17984] blktap2.activate
Oct 31 15:05:37 bi-first-server SM: [17984] lock: acquired /var/lock/sm/9d1e0648-3ca4-4e18-a5b8-626b2a131084/vdi
Oct 31 15:05:37 bi-first-server SM: [17984] Adding tag to: 9d1e0648-3ca4-4e18-a5b8-626b2a131084
Oct 31 15:05:37 bi-first-server SM: [17984] Activate lock succeeded
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:37 bi-first-server SM: [17984] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:37 bi-first-server SM: [17984] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [17984] '
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache: will initialize now
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache: refreshing
Oct 31 15:05:37 bi-first-server SM: [17984] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:37 bi-first-server SM: [17984] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [17984]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:37 bi-first-server SM: [17984] '
Oct 31 15:05:37 bi-first-server SM: [17984] LVHDVDI.attach for 9d1e0648-3ca4-4e18-a5b8-626b2a131084
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache: will initialize now
Oct 31 15:05:37 bi-first-server SM: [17984] LVMCache: refreshing
Oct 31 15:05:37 bi-first-server SM: [17984] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:37 bi-first-server SM: [17984] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [17984]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:37 bi-first-server SM: [17984] '
Oct 31 15:05:37 bi-first-server SM: [17984] Exception in activate/attach
Oct 31 15:05:37 bi-first-server SM: [17984] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for 9d1e0648-3ca4-4e18-a5b8-626b2a131084
Oct 31 15:05:37 bi-first-server SM: [17984] ***** BLKTAP2:<function _activate_locked at 0x904117c>: EXCEPTION util.CommandException, 5
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/blktap2.py", line 52, in wrapper
Oct 31 15:05:37 bi-first-server SM: [17984]     ret = op(self, *args)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/blktap2.py", line 1424, in _activate_locked
Oct 31 15:05:37 bi-first-server SM: [17984]     self._attach(sr_uuid, vdi_uuid)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/blktap2.py", line 1456, in _attach
Oct 31 15:05:37 bi-first-server SM: [17984]     attach_info = xmlrpclib.loads(self.target.attach(sr_uuid, vdi_uuid))[0][0]
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/blktap2.py", line 1012, in attach
Oct 31 15:05:37 bi-first-server SM: [17984]     return self.vdi.attach(sr_uuid, vdi_uuid)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/LVHDSR.py", line 1314, in attach
Oct 31 15:05:37 bi-first-server SM: [17984]     if self.sr.journaler.hasJournals(self.uuid):
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/journaler.py", line 70, in hasJournals
Oct 31 15:05:37 bi-first-server SM: [17984]     entries = self._getAllEntries()
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/journaler.py", line 80, in _getAllEntries
Oct 31 15:05:37 bi-first-server SM: [17984]     lvList = self.lvmCache.getTagged(self.LV_TAG)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/lvmcache.py", line 41, in wrapper
Oct 31 15:05:37 bi-first-server SM: [17984]     self.refresh()
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/lvmcache.py", line 71, in refresh
Oct 31 15:05:37 bi-first-server SM: [17984]     text = util.pread2(cmd)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/util.py", line 181, in pread2
Oct 31 15:05:37 bi-first-server SM: [17984]     return pread(cmdlist, quiet = quiet)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/util.py", line 174, in pread
Oct 31 15:05:37 bi-first-server SM: [17984]     raise CommandException(rc, str(cmdlist), stderr.strip())
Oct 31 15:05:37 bi-first-server SM: [17984] 
Oct 31 15:05:37 bi-first-server SM: [17984] Raising exception [46, The VDI is not available [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [17984]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]]
Oct 31 15:05:37 bi-first-server SM: [17984] lock: released /var/lock/sm/9d1e0648-3ca4-4e18-a5b8-626b2a131084/vdi
Oct 31 15:05:37 bi-first-server SM: [17984] ***** generic exception: vdi_activate: EXCEPTION SR.SROSError, The VDI is not available [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [17984]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/SRCommand.py", line 106, in run
Oct 31 15:05:37 bi-first-server SM: [17984]     return self._run_locked(sr)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/SRCommand.py", line 153, in _run_locked
Oct 31 15:05:37 bi-first-server SM: [17984]     return self._run(sr, target)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/SRCommand.py", line 246, in _run
Oct 31 15:05:37 bi-first-server SM: [17984]     writable, caching_params)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/blktap2.py", line 1395, in activate
Oct 31 15:05:37 bi-first-server SM: [17984]     writable, caching_params):
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/blktap2.py", line 60, in wrapper
Oct 31 15:05:37 bi-first-server SM: [17984]     raise xs_errors.XenError(excType, opterr=msg)
Oct 31 15:05:37 bi-first-server SM: [17984]   File "/opt/xensource/sm/xs_errors.py", line 49, in __init__
Oct 31 15:05:37 bi-first-server SM: [17984]     raise SR.SROSError(errorcode, errormessage)
Oct 31 15:05:37 bi-first-server SM: [17984] 
Oct 31 15:05:37 bi-first-server SM: [17984] lock: closed /var/lock/sm/9d1e0648-3ca4-4e18-a5b8-626b2a131084/vdi
Oct 31 15:05:37 bi-first-server SM: [17984] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:37 bi-first-server SM: [17984] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:37 bi-first-server SM: [18036] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:37 bi-first-server SM: [18036] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:37 bi-first-server SM: [18036] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [18036] '
Oct 31 15:05:37 bi-first-server SM: [18036] LVMCache: will initialize now
Oct 31 15:05:37 bi-first-server SM: [18036] LVMCache: refreshing
Oct 31 15:05:37 bi-first-server SM: [18036] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:05:37 bi-first-server SM: [18051] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:05:37 bi-first-server SM: [18051] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:37 bi-first-server SM: [18051]   pread SUCCESS
Oct 31 15:05:37 bi-first-server SM: [18051] lock: acquired /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:37 bi-first-server SM: [18051] LVMCache: will initialize now
Oct 31 15:05:37 bi-first-server SM: [18051] LVMCache: refreshing
Oct 31 15:05:37 bi-first-server SM: [18051] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:05:37 bi-first-server SM: [18051]   pread SUCCESS
Oct 31 15:05:37 bi-first-server SM: [18051] lock: released /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:05:37 bi-first-server SM: [18051] Entering _checkMetadataVolume
Oct 31 15:05:37 bi-first-server SM: [18036] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:05:37 bi-first-server SM: [18036]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:05:37 bi-first-server SM: [18036] '
Oct 31 15:05:37 bi-first-server SM: [18036] vdi_detach {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|e6ca69a7-7ee8-43ee-a656-7036fef74f2c|VDI.detach', 'vdi_ref': 'OpaqueRef:2b27a1d7-5d63-d096-8a35-7562296a1f14', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '9d1e0648-3ca4-4e18-a5b8-626b2a131084', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:9b1dbcee-64e9-6685-9c09-2d6885b6ada6', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '9d1e0648-3ca4-4e18-a5b8-626b2a131084'}
Oct 31 15:05:37 bi-first-server SM: [18036] lock: closed /var/lock/sm/9d1e0648-3ca4-4e18-a5b8-626b2a131084/vdi
Oct 31 15:05:37 bi-first-server SM: [18036] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:05:37 bi-first-server SM: [18051] vdi_epoch_end {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|805938de-1820-81fe-8c5b-ecf499095a34|VDI.epoch_end', 'vdi_ref': 'OpaqueRef:1257b946-3889-f94e-1ef9-82a2bb0d24c4', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:73441fb0-3a0a-35ab-e048-b464b54bb539', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_epoch_end', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'a7adcb30-5b14-4dce-8bc1-7f028a908c2d'}
Oct 31 15:05:37 bi-first-server SM: [18051] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:20:57 bi-first-server SM: [22812] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:57 bi-first-server SM: [22812] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:57 bi-first-server SM: [22812] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:57 bi-first-server SM: [22812] '
Oct 31 15:20:57 bi-first-server SM: [22812] LVMCache: will initialize now
Oct 31 15:20:57 bi-first-server SM: [22812] LVMCache: refreshing
Oct 31 15:20:57 bi-first-server SM: [22812] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:57 bi-first-server SM: [22812] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:57 bi-first-server SM: [22812]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:57 bi-first-server SM: [22812] '
Oct 31 15:20:57 bi-first-server SM: [22812] vdi_epoch_begin {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|02ca6344-6df0-45d7-c3f2-7ccceb53df84|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:f06c1844-4439-cab6-bbe4-bbf0b4082e3b', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '03e9d4f3-8faf-4f86-a307-796657b75fe2', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:790904fa-8a81-bc87-b1cc-e6c8ddbc1df2', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '03e9d4f3-8faf-4f86-a307-796657b75fe2'}
Oct 31 15:20:57 bi-first-server SM: [22812] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:20:57 bi-first-server SM: [22827] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:57 bi-first-server SM: [22827] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:57 bi-first-server SM: [22827] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:57 bi-first-server SM: [22827] '
Oct 31 15:20:57 bi-first-server SM: [22827] LVMCache: will initialize now
Oct 31 15:20:57 bi-first-server SM: [22827] LVMCache: refreshing
Oct 31 15:20:57 bi-first-server SM: [22827] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:57 bi-first-server SM: [22827] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:57 bi-first-server SM: [22827]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:57 bi-first-server SM: [22827] '
Oct 31 15:20:57 bi-first-server SM: [22827] vdi_attach {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|d50ad936-cdf5-07ac-0ab9-4f2c02e091db|VDI.attach', 'vdi_ref': 'OpaqueRef:f06c1844-4439-cab6-bbe4-bbf0b4082e3b', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '03e9d4f3-8faf-4f86-a307-796657b75fe2', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:3f81a1e8-7b5b-990c-9fdf-801581cca7f1', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '03e9d4f3-8faf-4f86-a307-796657b75fe2'}
Oct 31 15:20:57 bi-first-server SM: [22827] result: {'params': '/dev/sm/backend/bb66583f-2842-f789-6bc7-527355ce2044/03e9d4f3-8faf-4f86-a307-796657b75fe2', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEjAzZTlkNGYzLThmYWYtNGYgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIDAzZTlkNGYzLThmYWYtNGY4Ni1hMzA3LTc5NjY1N2I3NWZlMiA=', 'vdi-uuid': '03e9d4f3-8faf-4f86-a307-796657b75fe2', 'mem-pool': 'bb66583f-2842-f789-6bc7-527355ce2044'}}
Oct 31 15:20:57 bi-first-server SM: [22827] lock: closed /var/lock/sm/03e9d4f3-8faf-4f86-a307-796657b75fe2/vdi
Oct 31 15:20:57 bi-first-server SM: [22827] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:20:57 bi-first-server SM: [22859] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:57 bi-first-server SM: [22859] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:57 bi-first-server SM: [22859] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:57 bi-first-server SM: [22859] '
Oct 31 15:20:57 bi-first-server SM: [22859] LVMCache: will initialize now
Oct 31 15:20:57 bi-first-server SM: [22859] LVMCache: refreshing
Oct 31 15:20:57 bi-first-server SM: [22859] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:57 bi-first-server SM: [22859] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:57 bi-first-server SM: [22859]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:57 bi-first-server SM: [22859] '
Oct 31 15:20:57 bi-first-server SM: [22859] vdi_activate {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|f472807b-cbc1-8be1-b760-0f4f302834e1|VDI.activate', 'vdi_ref': 'OpaqueRef:f06c1844-4439-cab6-bbe4-bbf0b4082e3b', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '03e9d4f3-8faf-4f86-a307-796657b75fe2', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:ea1f9bec-a750-d519-3153-7560ada1d06c', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '03e9d4f3-8faf-4f86-a307-796657b75fe2'}
Oct 31 15:20:57 bi-first-server SM: [22859] blktap2.activate
Oct 31 15:20:57 bi-first-server SM: [22859] lock: acquired /var/lock/sm/03e9d4f3-8faf-4f86-a307-796657b75fe2/vdi
Oct 31 15:20:57 bi-first-server SM: [22859] Adding tag to: 03e9d4f3-8faf-4f86-a307-796657b75fe2
Oct 31 15:20:57 bi-first-server SM: [22859] Activate lock succeeded
Oct 31 15:20:57 bi-first-server SM: [22859] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:57 bi-first-server SM: [22859] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:58 bi-first-server SM: [22859] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:58 bi-first-server SM: [22859] '
Oct 31 15:20:58 bi-first-server SM: [22859] LVMCache: will initialize now
Oct 31 15:20:58 bi-first-server SM: [22859] LVMCache: refreshing
Oct 31 15:20:58 bi-first-server SM: [22859] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:58 bi-first-server SM: [22859] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:58 bi-first-server SM: [22859]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:58 bi-first-server SM: [22859] '
Oct 31 15:20:58 bi-first-server SM: [22859] LVHDVDI.attach for 03e9d4f3-8faf-4f86-a307-796657b75fe2
Oct 31 15:20:58 bi-first-server SM: [22859] LVMCache: will initialize now
Oct 31 15:20:58 bi-first-server SM: [22859] LVMCache: refreshing
Oct 31 15:20:58 bi-first-server SM: [22859] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:58 bi-first-server SM: [22859] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:58 bi-first-server SM: [22859]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:58 bi-first-server SM: [22859] '
Oct 31 15:20:58 bi-first-server SM: [22859] Exception in activate/attach
Oct 31 15:20:58 bi-first-server SM: [22859] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for 03e9d4f3-8faf-4f86-a307-796657b75fe2
Oct 31 15:20:58 bi-first-server SM: [22859] ***** BLKTAP2:<function _activate_locked at 0x953217c>: EXCEPTION util.CommandException, 5
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/blktap2.py", line 52, in wrapper
Oct 31 15:20:58 bi-first-server SM: [22859]     ret = op(self, *args)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/blktap2.py", line 1424, in _activate_locked
Oct 31 15:20:58 bi-first-server SM: [22859]     self._attach(sr_uuid, vdi_uuid)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/blktap2.py", line 1456, in _attach
Oct 31 15:20:58 bi-first-server SM: [22859]     attach_info = xmlrpclib.loads(self.target.attach(sr_uuid, vdi_uuid))[0][0]
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/blktap2.py", line 1012, in attach
Oct 31 15:20:58 bi-first-server SM: [22859]     return self.vdi.attach(sr_uuid, vdi_uuid)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/LVHDSR.py", line 1314, in attach
Oct 31 15:20:58 bi-first-server SM: [22859]     if self.sr.journaler.hasJournals(self.uuid):
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/journaler.py", line 70, in hasJournals
Oct 31 15:20:58 bi-first-server SM: [22859]     entries = self._getAllEntries()
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/journaler.py", line 80, in _getAllEntries
Oct 31 15:20:58 bi-first-server SM: [22859]     lvList = self.lvmCache.getTagged(self.LV_TAG)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/lvmcache.py", line 41, in wrapper
Oct 31 15:20:58 bi-first-server SM: [22859]     self.refresh()
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/lvmcache.py", line 71, in refresh
Oct 31 15:20:58 bi-first-server SM: [22859]     text = util.pread2(cmd)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/util.py", line 181, in pread2
Oct 31 15:20:58 bi-first-server SM: [22859]     return pread(cmdlist, quiet = quiet)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/util.py", line 174, in pread
Oct 31 15:20:58 bi-first-server SM: [22859]     raise CommandException(rc, str(cmdlist), stderr.strip())
Oct 31 15:20:58 bi-first-server SM: [22859] 
Oct 31 15:20:58 bi-first-server SM: [22859] Raising exception [46, The VDI is not available [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:58 bi-first-server SM: [22859]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]]
Oct 31 15:20:58 bi-first-server SM: [22859] lock: released /var/lock/sm/03e9d4f3-8faf-4f86-a307-796657b75fe2/vdi
Oct 31 15:20:58 bi-first-server SM: [22859] ***** generic exception: vdi_activate: EXCEPTION SR.SROSError, The VDI is not available [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:58 bi-first-server SM: [22859]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/SRCommand.py", line 106, in run
Oct 31 15:20:58 bi-first-server SM: [22859]     return self._run_locked(sr)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/SRCommand.py", line 153, in _run_locked
Oct 31 15:20:58 bi-first-server SM: [22859]     return self._run(sr, target)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/SRCommand.py", line 246, in _run
Oct 31 15:20:58 bi-first-server SM: [22859]     writable, caching_params)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/blktap2.py", line 1395, in activate
Oct 31 15:20:58 bi-first-server SM: [22859]     writable, caching_params):
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/blktap2.py", line 60, in wrapper
Oct 31 15:20:58 bi-first-server SM: [22859]     raise xs_errors.XenError(excType, opterr=msg)
Oct 31 15:20:58 bi-first-server SM: [22859]   File "/opt/xensource/sm/xs_errors.py", line 49, in __init__
Oct 31 15:20:58 bi-first-server SM: [22859]     raise SR.SROSError(errorcode, errormessage)
Oct 31 15:20:58 bi-first-server SM: [22859] 
Oct 31 15:20:58 bi-first-server SM: [22859] lock: closed /var/lock/sm/03e9d4f3-8faf-4f86-a307-796657b75fe2/vdi
Oct 31 15:20:58 bi-first-server SM: [22859] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:20:58 bi-first-server SM: [22859] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:20:58 bi-first-server SM: [22896] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:58 bi-first-server SM: [22896] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:58 bi-first-server SM: [22896] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:58 bi-first-server SM: [22896] '
Oct 31 15:20:58 bi-first-server SM: [22896] LVMCache: will initialize now
Oct 31 15:20:58 bi-first-server SM: [22896] LVMCache: refreshing
Oct 31 15:20:58 bi-first-server SM: [22896] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:20:58 bi-first-server SM: [22896] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:20:58 bi-first-server SM: [22896]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:20:58 bi-first-server SM: [22896] '
Oct 31 15:20:58 bi-first-server SM: [22896] vdi_detach {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|ecdd38a0-16c7-3f54-0b45-7878814b1ae4|VDI.detach', 'vdi_ref': 'OpaqueRef:f06c1844-4439-cab6-bbe4-bbf0b4082e3b', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '03e9d4f3-8faf-4f86-a307-796657b75fe2', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:c684f4fa-d525-b155-5b57-c0f1cd4ed777', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '03e9d4f3-8faf-4f86-a307-796657b75fe2'}
Oct 31 15:20:58 bi-first-server SM: [22896] lock: closed /var/lock/sm/03e9d4f3-8faf-4f86-a307-796657b75fe2/vdi
Oct 31 15:20:58 bi-first-server SM: [22896] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:24:09 bi-first-server SM: [23890] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:24:09 bi-first-server SM: [23890] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:24:09 bi-first-server SM: [23890] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:24:09 bi-first-server SM: [23890] '
Oct 31 15:24:09 bi-first-server SM: [23890] LVMCache: will initialize now
Oct 31 15:24:09 bi-first-server SM: [23890] LVMCache: refreshing
Oct 31 15:24:09 bi-first-server SM: [23890] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:24:09 bi-first-server SM: [23890] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:24:09 bi-first-server SM: [23890]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:24:09 bi-first-server SM: [23890] '
Oct 31 15:24:09 bi-first-server SM: [23890] lock: acquired /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:24:09 bi-first-server SM: [23890] sr_scan {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|9aa2cb04-ee8c-286e-0d16-674775fef699|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:5e15e562-e85b-7409-8b84-246410aa9f69', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73'}
Oct 31 15:24:09 bi-first-server SM: [23890] LVHDSR.scan for bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:24:09 bi-first-server SM: [23890] LVMCache: refreshing
Oct 31 15:24:09 bi-first-server SM: [23890] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:24:09 bi-first-server SM: [23890] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:24:09 bi-first-server SM: [23890]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:24:09 bi-first-server SM: [23890] '
Oct 31 15:24:09 bi-first-server SM: [23890] lock: released /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:24:09 bi-first-server SM: [23890] ***** sr_scan: EXCEPTION util.CommandException, 5
Oct 31 15:24:09 bi-first-server SM: [23890]   File "/opt/xensource/sm/SRCommand.py", line 106, in run
Oct 31 15:24:09 bi-first-server SM: [23890]     return self._run_locked(sr)
Oct 31 15:24:09 bi-first-server SM: [23890]   File "/opt/xensource/sm/SRCommand.py", line 153, in _run_locked
Oct 31 15:24:09 bi-first-server SM: [23890]     return self._run(sr, target)
Oct 31 15:24:09 bi-first-server SM: [23890]   File "/opt/xensource/sm/SRCommand.py", line 313, in _run
Oct 31 15:24:09 bi-first-server SM: [23890]     return sr.scan(self.params['sr_uuid'])
Oct 31 15:24:09 bi-first-server SM: [23890]   File "/opt/xensource/sm/LVMSR", line 607, in scan
Oct 31 15:24:09 bi-first-server SM: [23890]     self.lvmCache.refresh()
Oct 31 15:24:09 bi-first-server SM: [23890]   File "/opt/xensource/sm/lvmcache.py", line 71, in refresh
Oct 31 15:24:09 bi-first-server SM: [23890]     text = util.pread2(cmd)
Oct 31 15:24:09 bi-first-server SM: [23890]   File "/opt/xensource/sm/util.py", line 181, in pread2
Oct 31 15:24:09 bi-first-server SM: [23890]     return pread(cmdlist, quiet = quiet)
Oct 31 15:24:09 bi-first-server SM: [23890]   File "/opt/xensource/sm/util.py", line 174, in pread
Oct 31 15:24:09 bi-first-server SM: [23890]     raise CommandException(rc, str(cmdlist), stderr.strip())
Oct 31 15:24:09 bi-first-server SM: [23890] 
Oct 31 15:24:09 bi-first-server SM: [23890] Raising exception [40, The SR scan failed  [opterr=Command ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044'] failed (5): Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:24:09 bi-first-server SM: [23890]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044]]
Oct 31 15:24:09 bi-first-server SM: [23890] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:29:09 bi-first-server SM: [25404] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:29:09 bi-first-server SM: [25404] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:09 bi-first-server SM: [25404]   pread SUCCESS
Oct 31 15:29:09 bi-first-server SM: [25404] lock: acquired /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:29:09 bi-first-server SM: [25404] LVMCache: will initialize now
Oct 31 15:29:09 bi-first-server SM: [25404] LVMCache: refreshing
Oct 31 15:29:09 bi-first-server SM: [25404] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:09 bi-first-server SM: [25404]   pread SUCCESS
Oct 31 15:29:09 bi-first-server SM: [25404] lock: released /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:29:09 bi-first-server SM: [25404] Entering _checkMetadataVolume
Oct 31 15:29:09 bi-first-server SM: [25404] vdi_epoch_begin {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|af09b71c-3c0a-2b31-953e-8e495888dff5|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:15038f08-d5f9-9f3e-b022-2920d87ec157', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:29:09 bi-first-server SM: [25404] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:29:10 bi-first-server SM: [25422] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:29:10 bi-first-server SM: [25422] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:10 bi-first-server SM: [25422]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25422] Entering _checkMetadataVolume
Oct 31 15:29:10 bi-first-server SM: [25422] LVMCache: will initialize now
Oct 31 15:29:10 bi-first-server SM: [25422] LVMCache: refreshing
Oct 31 15:29:10 bi-first-server SM: [25422] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:10 bi-first-server SM: [25422]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25422] vdi_attach {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|520055b4-67a1-b28c-9a07-fb163cc87c83|VDI.attach', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:8eb6cae2-1f6b-d35f-b1b3-7ab97e6ac682', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:29:10 bi-first-server SM: [25422] result: {'params': '/dev/sm/backend/d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEmI3YzQyMTRhLTExYmMtNDcgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIGI3YzQyMTRhLTExYmMtNDc5Zi04Mjg5LWUwOTBjZjYxZGY2YyA=', 'vdi-uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'mem-pool': 'd87cdd6c-d520-1250-637c-a3f42c8da868'}}
Oct 31 15:29:10 bi-first-server SM: [25422] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:29:10 bi-first-server SM: [25422] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:29:10 bi-first-server SM: [25442] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:10 bi-first-server SM: [25442]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25442] Entering _checkMetadataVolume
Oct 31 15:29:10 bi-first-server SM: [25442] LVMCache: will initialize now
Oct 31 15:29:10 bi-first-server SM: [25442] LVMCache: refreshing
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:10 bi-first-server SM: [25442]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25442] vdi_activate {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|acc9fbe7-23f3-839f-9fed-4416859f97de|VDI.activate', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:e9ded408-3962-995e-80c9-5ac7cd9ff74e', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:29:10 bi-first-server SM: [25442] blktap2.activate
Oct 31 15:29:10 bi-first-server SM: [25442] lock: acquired /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:29:10 bi-first-server SM: [25442] Adding tag to: b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] Activate lock succeeded
Oct 31 15:29:10 bi-first-server SM: [25442] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:10 bi-first-server SM: [25442]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25442] Entering _checkMetadataVolume
Oct 31 15:29:10 bi-first-server SM: [25442] LVMCache: will initialize now
Oct 31 15:29:10 bi-first-server SM: [25442] LVMCache: refreshing
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:29:10 bi-first-server SM: [25442]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25442] LVHDVDI.attach for b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (0, 0) + (1, 0) => (1, 0)
Oct 31 15:29:10 bi-first-server SM: [25442] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (1, 0b)
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/lvchange', '-ay', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:29:10 bi-first-server SM: [25442]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25442] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:29:10 bi-first-server SM: [25442]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25442] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-b7c4214a-11bc-479f-8289-e090cf61df6c', '-l', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868', '-a']
Oct 31 15:29:10 bi-first-server SM: [25442]   pread SUCCESS
Oct 31 15:29:10 bi-first-server SM: [25442] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (1, 0) + (0, 1) => (1, 1)
Oct 31 15:29:10 bi-first-server SM: [25442] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (1, 1b)
Oct 31 15:29:10 bi-first-server SM: [25442] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:29:10 bi-first-server SM: [25442] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (0, 1b)
Oct 31 15:29:10 bi-first-server SM: [25442] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] PhyLink(/dev/sm/phy/d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c) -> /dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/tap-ctl', 'allocate']
Oct 31 15:29:10 bi-first-server SM: [25442]  = 0
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/tap-ctl', 'spawn']
Oct 31 15:29:10 bi-first-server SM: [25442]  = 0
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/tap-ctl', 'attach', '-p', '25493', '-m', '20']
Oct 31 15:29:10 bi-first-server SM: [25442]  = 0
Oct 31 15:29:10 bi-first-server SM: [25442] ['/usr/sbin/tap-ctl', 'open', '-p', '25493', '-m', '20', '-a', 'vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:29:10 bi-first-server SM: [25442]  = 0
Oct 31 15:29:10 bi-first-server SM: [25442] tap.activate: Launched Tapdisk(vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c, pid=25493, minor=20, state=R)
Oct 31 15:29:10 bi-first-server SM: [25442] DeviceNode(/dev/sm/backend/d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c) -> /dev/xen/blktap-2/tapdev20
Oct 31 15:29:10 bi-first-server SM: [25442] lock: released /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:29:10 bi-first-server SM: [25442] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:29:10 bi-first-server SM: [25442] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:29:10 bi-first-server SM: [25442] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:30:17 bi-first-server SM: [26265] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:30:17 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:17 bi-first-server SM: [26265] Entering _checkMetadataVolume
Oct 31 15:30:17 bi-first-server SM: [26265] LVMCache: will initialize now
Oct 31 15:30:17 bi-first-server SM: [26265] LVMCache: refreshing
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:30:17 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:17 bi-first-server SM: [26265] vdi_deactivate {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|b1dc5052-5316-901e-0689-501b26e094e2|VDI.deactivate', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:7c6345c2-5b91-bd02-9243-d6b178662c9f', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_deactivate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:30:17 bi-first-server SM: [26265] blktap2.deactivate
Oct 31 15:30:17 bi-first-server SM: [26265] lock: acquired /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/tap-ctl', 'close', '-p', '25493', '-m', '20']
Oct 31 15:30:17 bi-first-server SM: [26265]  = 0
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/tap-ctl', 'detach', '-p', '25493', '-m', '20']
Oct 31 15:30:17 bi-first-server SM: [26265]  = 0
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/tap-ctl', 'free', '-m', '20']
Oct 31 15:30:17 bi-first-server SM: [26265]  = 0
Oct 31 15:30:17 bi-first-server SM: [26265] tap.deactivate: Shut down Tapdisk(vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c, pid=25493, minor=20, state=R)
Oct 31 15:30:17 bi-first-server SM: [26265] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:30:17 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:17 bi-first-server SM: [26265] Entering _checkMetadataVolume
Oct 31 15:30:17 bi-first-server SM: [26265] LVMCache: will initialize now
Oct 31 15:30:17 bi-first-server SM: [26265] LVMCache: refreshing
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:30:17 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:17 bi-first-server SM: [26265] LVHDVDI.detach for b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (0, 1) + (1, 0) => (1, 1)
Oct 31 15:30:17 bi-first-server SM: [26265] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (1, 1b)
Oct 31 15:30:17 bi-first-server SM: [26265] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:30:17 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:17 bi-first-server SM: [26265] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-b7c4214a-11bc-479f-8289-e090cf61df6c', '-l', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868', '-a']
Oct 31 15:30:17 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:17 bi-first-server SM: [26265] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:30:17 bi-first-server SM: [26265] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (0, 1b)
Oct 31 15:30:17 bi-first-server SM: [26265] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:17 bi-first-server SM: [26265] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (0, 1) + (0, -1) => (0, 0)
Oct 31 15:30:17 bi-first-server SM: [26265] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (0, 0b)
Oct 31 15:30:17 bi-first-server SM: [26265] ['/usr/sbin/lvchange', '-an', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:30:18 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:18 bi-first-server SM: [26265] ['/sbin/dmsetup', 'status', 'VG_XenStorage--d87cdd6c--d520--1250--637c--a3f42c8da868-VHD--b7c4214a--11bc--479f--8289--e090cf61df6c']
Oct 31 15:30:18 bi-first-server SM: [26265]   pread SUCCESS
Oct 31 15:30:18 bi-first-server SM: [26265] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:18 bi-first-server SM: [26265] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:18 bi-first-server SM: [26265] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:30:18 bi-first-server SM: [26265] lock: released /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:30:18 bi-first-server SM: [26265] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:30:18 bi-first-server SM: [26265] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:30:18 bi-first-server SM: [26265] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:30:18 bi-first-server SM: [26351] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:30:18 bi-first-server SM: [26351] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:30:18 bi-first-server SM: [26351]   pread SUCCESS
Oct 31 15:30:18 bi-first-server SM: [26351] Entering _checkMetadataVolume
Oct 31 15:30:18 bi-first-server SM: [26351] LVMCache: will initialize now
Oct 31 15:30:18 bi-first-server SM: [26351] LVMCache: refreshing
Oct 31 15:30:18 bi-first-server SM: [26351] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:30:18 bi-first-server SM: [26351]   pread SUCCESS
Oct 31 15:30:18 bi-first-server SM: [26351] vdi_detach {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|cfac22e8-6459-5404-343b-71be4d7afd47|VDI.detach', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:5323424f-5ec7-9d3e-4032-a8cb2f0cf246', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:30:18 bi-first-server SM: [26351] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:30:18 bi-first-server SM: [26351] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:31:34 bi-first-server SM: [26882] ['uuidgen', '-r']
Oct 31 15:31:34 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:34 bi-first-server SM: [26882] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:31:34 bi-first-server SM: [26882] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:34 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:34 bi-first-server SM: [26882] lock: acquired /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:31:34 bi-first-server SM: [26882] LVMCache: will initialize now
Oct 31 15:31:34 bi-first-server SM: [26882] LVMCache: refreshing
Oct 31 15:31:34 bi-first-server SM: [26882] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:34 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:34 bi-first-server SM: [26882] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:31:34 bi-first-server SM: [26882] Entering _checkMetadataVolume
Oct 31 15:31:34 bi-first-server SM: [26882] lock: acquired /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:31:34 bi-first-server SM: [26882] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] vdi_create {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|dcbfff64-02cb-fd6e-d613-f7fdc23064c1|VDI.create', 'vdi_type': 'system', 'args': ['21474836480', '_Clean_Ubuntu_16.04x64 0', 'Created by template provisioner', '', 'false', '19700101T00:00:00Z', '', 'false'], 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:65c668bb-4dc4-89fd-bebb-5681a859eccd', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_create', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_sm_config': {}}
Oct 31 15:31:35 bi-first-server SM: [26882] LVHDVDI.create for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:35 bi-first-server SM: [26882] LVHDVDI.create: type = vhd, /dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240 (size=21474836480)
Oct 31 15:31:35 bi-first-server SM: [26882] ['/usr/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] ['/usr/sbin/lvcreate', '-n', 'VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240', '-L', '20528', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] ['/usr/bin/vhd-util', 'create', '--debug', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240', '-s', '20480', '-S', '2097152']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] ['/usr/bin/vhd-util', 'query', '--debug', '-v', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] ['/usr/sbin/lvchange', '-an', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] ['/sbin/dmsetup', 'status', 'VG_XenStorage--bf62168d--e6c9--7e0a--cf76--821de372530d-VHD--8a029a2c--ef42--4975--8a0c--1731d7b88240']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] Checking if there is space in the metadata for 1 VDI.
Oct 31 15:31:35 bi-first-server SM: [26882] ['uuidgen', '-r']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] Entering addVdiInternal
Oct 31 15:31:35 bi-first-server SM: [26882] Entering getMetadataToWrite
Oct 31 15:31:35 bi-first-server SM: [26882] Entering VDI info
Oct 31 15:31:35 bi-first-server SM: [26882] Entering deleteVdi
Oct 31 15:31:35 bi-first-server SM: [26882] entering updateVdi
Oct 31 15:31:35 bi-first-server SM: [26882] Entering getMetadataToWrite
Oct 31 15:31:35 bi-first-server SM: [26882] Entering VDI info
Oct 31 15:31:35 bi-first-server SM: [26882] Entering VDI info
Oct 31 15:31:35 bi-first-server SM: [26882] ['/usr/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:35 bi-first-server SM: [26882]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26882] Adding VDI with info: {'read_only': 0, 'managed': 1, 'snapshot_time': '', 'vdi_type': 'vhd', 'snapshot_of': '', 'name_label': '_Clean_Ubuntu_16.04x64 0', 'name_description': 'Created by template provisioner', 'type': 'system', 'metadata_of_pool': '', 'is_a_snapshot': 0, 'uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:31:35 bi-first-server SM: [26882] Entering addVdiInternal
Oct 31 15:31:35 bi-first-server SM: [26882] Entering getMetadataToWrite
Oct 31 15:31:35 bi-first-server SM: [26882] Entering VDI info
Oct 31 15:31:35 bi-first-server SM: [26882] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:31:35 bi-first-server SM: [26882] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:31:35 bi-first-server SM: [26937] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:31:35 bi-first-server SM: [26937] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:31:35 bi-first-server SM: [26937]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26937] Entering _checkMetadataVolume
Oct 31 15:31:35 bi-first-server SM: [26937] LVMCache: will initialize now
Oct 31 15:31:35 bi-first-server SM: [26937] LVMCache: refreshing
Oct 31 15:31:35 bi-first-server SM: [26937] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:31:35 bi-first-server SM: [26937]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26937] vdi_attach {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|667dd9ce-fa6f-9a4e-8fa9-6f7f0fb0fbf4|VDI.attach', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': ['false'], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:12f1e617-29ef-8918-7f3b-f466f9569db4', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:31:35 bi-first-server SM: [26937] result: {'params': '/dev/sm/backend/d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEmI3YzQyMTRhLTExYmMtNDcgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIGI3YzQyMTRhLTExYmMtNDc5Zi04Mjg5LWUwOTBjZjYxZGY2YyA=', 'vdi-uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'mem-pool': 'd87cdd6c-d520-1250-637c-a3f42c8da868'}}
Oct 31 15:31:35 bi-first-server SM: [26937] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:31:35 bi-first-server SM: [26937] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:31:35 bi-first-server SM: [26961] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:31:35 bi-first-server SM: [26961] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:31:35 bi-first-server SM: [26961]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26961] Entering _checkMetadataVolume
Oct 31 15:31:35 bi-first-server SM: [26961] LVMCache: will initialize now
Oct 31 15:31:35 bi-first-server SM: [26961] LVMCache: refreshing
Oct 31 15:31:35 bi-first-server SM: [26961] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:31:35 bi-first-server SM: [26961]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26961] vdi_activate {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|39edfe61-aa12-c84d-166e-e026dd1a66c1|VDI.activate', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': ['false'], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:2f606134-65e5-de04-7ed3-bf81cc3ba976', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:31:35 bi-first-server SM: [26961] blktap2.activate
Oct 31 15:31:35 bi-first-server SM: [26961] lock: acquired /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:31:35 bi-first-server SM: [26961] Adding tag to: b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:35 bi-first-server SM: [26961] Activate lock succeeded
Oct 31 15:31:35 bi-first-server SM: [26961] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:31:35 bi-first-server SM: [26961] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:31:35 bi-first-server SM: [26961]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26961] Entering _checkMetadataVolume
Oct 31 15:31:35 bi-first-server SM: [26961] LVMCache: will initialize now
Oct 31 15:31:35 bi-first-server SM: [26961] LVMCache: refreshing
Oct 31 15:31:35 bi-first-server SM: [26961] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:31:35 bi-first-server SM: [26961]   pread SUCCESS
Oct 31 15:31:35 bi-first-server SM: [26961] LVHDVDI.attach for b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:35 bi-first-server SM: [26961] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:35 bi-first-server SM: [26961] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:35 bi-first-server SM: [26961] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:35 bi-first-server SM: [26961] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-b7c4214a-11bc-479f-8289-e090cf61df6c', '-l', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868', '-a']
Oct 31 15:31:36 bi-first-server SM: [26961]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [26961] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:36 bi-first-server SM: [26961] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (0, 0) + (0, 1) => (0, 1)
Oct 31 15:31:36 bi-first-server SM: [26961] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (0, 1b)
Oct 31 15:31:36 bi-first-server SM: [26961] ['/usr/sbin/lvchange', '-ay', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:31:36 bi-first-server SM: [26961]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [26961] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:36 bi-first-server SM: [26961] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:36 bi-first-server SM: [26961] PhyLink(/dev/sm/phy/d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c) -> /dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:31:36 bi-first-server SM: [26961] ['/usr/sbin/tap-ctl', 'allocate']
Oct 31 15:31:36 bi-first-server SM: [26961]  = 0
Oct 31 15:31:36 bi-first-server SM: [26961] ['/usr/sbin/tap-ctl', 'spawn']
Oct 31 15:31:36 bi-first-server SM: [26961]  = 0
Oct 31 15:31:36 bi-first-server SM: [26961] ['/usr/sbin/tap-ctl', 'attach', '-p', '27019', '-m', '20']
Oct 31 15:31:36 bi-first-server SM: [26961]  = 0
Oct 31 15:31:36 bi-first-server SM: [26961] ['/usr/sbin/tap-ctl', 'open', '-p', '27019', '-m', '20', '-a', 'vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c', '-R']
Oct 31 15:31:36 bi-first-server SM: [26961]  = 0
Oct 31 15:31:36 bi-first-server SM: [26961] tap.activate: Launched Tapdisk(vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c, pid=27019, minor=20, state=R)
Oct 31 15:31:36 bi-first-server SM: [26961] DeviceNode(/dev/sm/backend/d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c) -> /dev/xen/blktap-2/tapdev20
Oct 31 15:31:36 bi-first-server SM: [26961] lock: released /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:31:36 bi-first-server SM: [26961] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:31:36 bi-first-server SM: [26961] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:31:36 bi-first-server SM: [26961] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:31:36 bi-first-server SM: [27037] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:31:36 bi-first-server SM: [27037] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:36 bi-first-server SM: [27037]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27037] Entering _checkMetadataVolume
Oct 31 15:31:36 bi-first-server SM: [27037] LVMCache: will initialize now
Oct 31 15:31:36 bi-first-server SM: [27037] LVMCache: refreshing
Oct 31 15:31:36 bi-first-server SM: [27037] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:36 bi-first-server SM: [27037]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27037] vdi_attach {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|f3683db2-647a-7b00-aae8-5b35d8120de1|VDI.attach', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:6a093d81-c5d1-5ead-429f-0b91c9ae1df5', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:31:36 bi-first-server SM: [27037] lock: creating lock file /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:31:36 bi-first-server SM: [27037] result: {'params': '/dev/sm/backend/bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEjhhMDI5YTJjLWVmNDItNDkgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIDhhMDI5YTJjLWVmNDItNDk3NS04YTBjLTE3MzFkN2I4ODI0MCA=', 'vdi-uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'mem-pool': 'bf62168d-e6c9-7e0a-cf76-821de372530d'}}
Oct 31 15:31:36 bi-first-server SM: [27037] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:31:36 bi-first-server SM: [27037] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:31:36 bi-first-server SM: [27066] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:36 bi-first-server SM: [27066]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27066] Entering _checkMetadataVolume
Oct 31 15:31:36 bi-first-server SM: [27066] LVMCache: will initialize now
Oct 31 15:31:36 bi-first-server SM: [27066] LVMCache: refreshing
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:36 bi-first-server SM: [27066]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27066] vdi_activate {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|3e328f36-1430-a224-437a-6a3acb84e967|VDI.activate', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:0f1c064f-c481-d94e-9100-5114fa62bf7b', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:31:36 bi-first-server SM: [27066] blktap2.activate
Oct 31 15:31:36 bi-first-server SM: [27066] lock: acquired /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:31:36 bi-first-server SM: [27066] Adding tag to: 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] Activate lock succeeded
Oct 31 15:31:36 bi-first-server SM: [27066] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:36 bi-first-server SM: [27066]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27066] Entering _checkMetadataVolume
Oct 31 15:31:36 bi-first-server SM: [27066] LVMCache: will initialize now
Oct 31 15:31:36 bi-first-server SM: [27066] LVMCache: refreshing
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:31:36 bi-first-server SM: [27066]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27066] LVHDVDI.attach for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: creating lock file /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (0, 0) + (1, 0) => (1, 0)
Oct 31 15:31:36 bi-first-server SM: [27066] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (1, 0b)
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/lvchange', '-ay', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:31:36 bi-first-server SM: [27066]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27066] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:31:36 bi-first-server SM: [27066]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27066] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240', '-l', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d', '-a']
Oct 31 15:31:36 bi-first-server SM: [27066]   pread SUCCESS
Oct 31 15:31:36 bi-first-server SM: [27066] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (1, 0) + (0, 1) => (1, 1)
Oct 31 15:31:36 bi-first-server SM: [27066] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (1, 1b)
Oct 31 15:31:36 bi-first-server SM: [27066] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:31:36 bi-first-server SM: [27066] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (0, 1b)
Oct 31 15:31:36 bi-first-server SM: [27066] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] PhyLink(/dev/sm/phy/bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240) -> /dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/tap-ctl', 'allocate']
Oct 31 15:31:36 bi-first-server SM: [27066]  = 0
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/tap-ctl', 'spawn']
Oct 31 15:31:36 bi-first-server SM: [27066]  = 0
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/tap-ctl', 'attach', '-p', '27125', '-m', '21']
Oct 31 15:31:36 bi-first-server SM: [27066]  = 0
Oct 31 15:31:36 bi-first-server SM: [27066] ['/usr/sbin/tap-ctl', 'open', '-p', '27125', '-m', '21', '-a', 'vhd:/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:31:36 bi-first-server SM: [27066]  = 0
Oct 31 15:31:36 bi-first-server SM: [27066] tap.activate: Launched Tapdisk(vhd:/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240, pid=27125, minor=21, state=R)
Oct 31 15:31:36 bi-first-server SM: [27066] DeviceNode(/dev/sm/backend/bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240) -> /dev/xen/blktap-2/tapdev21
Oct 31 15:31:36 bi-first-server SM: [27066] lock: released /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:31:36 bi-first-server SM: [27066] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:31:36 bi-first-server SM: [27066] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:31:36 bi-first-server SM: [27066] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:08 bi-first-server SM: [27503] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:32:08 bi-first-server SM: [27503] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:08 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:08 bi-first-server SM: [27503] Entering _checkMetadataVolume
Oct 31 15:32:08 bi-first-server SM: [27503] LVMCache: will initialize now
Oct 31 15:32:08 bi-first-server SM: [27503] LVMCache: refreshing
Oct 31 15:32:08 bi-first-server SM: [27503] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:08 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:08 bi-first-server SM: [27503] vdi_deactivate {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|8eb0e2e8-4dc9-9234-caac-056faaf5684a|VDI.deactivate', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:6a28004a-78a1-56ea-f8db-3c585c7fb33b', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_deactivate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:32:08 bi-first-server SM: [27503] blktap2.deactivate
Oct 31 15:32:08 bi-first-server SM: [27503] lock: acquired /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:08 bi-first-server SM: [27503] unlink of attach_info failed
Oct 31 15:32:08 bi-first-server SM: [27503] ['/usr/sbin/tap-ctl', 'close', '-p', '27125', '-m', '21']
Oct 31 15:32:08 bi-first-server SM: [27503]  = 0
Oct 31 15:32:08 bi-first-server SM: [27503] ['/usr/sbin/tap-ctl', 'detach', '-p', '27125', '-m', '21']
Oct 31 15:32:08 bi-first-server SM: [27503]  = 0
Oct 31 15:32:08 bi-first-server SM: [27503] ['/usr/sbin/tap-ctl', 'free', '-m', '21']
Oct 31 15:32:08 bi-first-server SM: [27503]  = 0
Oct 31 15:32:08 bi-first-server SM: [27503] tap.deactivate: Shut down Tapdisk(vhd:/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240, pid=27125, minor=21, state=R)
Oct 31 15:32:09 bi-first-server SM: [27503] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:32:09 bi-first-server SM: [27503] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:09 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27503] Entering _checkMetadataVolume
Oct 31 15:32:09 bi-first-server SM: [27503] LVMCache: will initialize now
Oct 31 15:32:09 bi-first-server SM: [27503] LVMCache: refreshing
Oct 31 15:32:09 bi-first-server SM: [27503] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:09 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27503] LVHDVDI.detach for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (0, 1) + (1, 0) => (1, 1)
Oct 31 15:32:09 bi-first-server SM: [27503] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (1, 1b)
Oct 31 15:32:09 bi-first-server SM: [27503] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:32:09 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27503] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240', '-l', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d', '-a']
Oct 31 15:32:09 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27503] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:32:09 bi-first-server SM: [27503] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (0, 1b)
Oct 31 15:32:09 bi-first-server SM: [27503] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (0, 1) + (0, -1) => (0, 0)
Oct 31 15:32:09 bi-first-server SM: [27503] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (0, 0b)
Oct 31 15:32:09 bi-first-server SM: [27503] ['/usr/sbin/lvchange', '-an', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:32:09 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27503] ['/sbin/dmsetup', 'status', 'VG_XenStorage--bf62168d--e6c9--7e0a--cf76--821de372530d-VHD--8a029a2c--ef42--4975--8a0c--1731d7b88240']
Oct 31 15:32:09 bi-first-server SM: [27503]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27503] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:09 bi-first-server SM: [27503] lock: released /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:09 bi-first-server SM: [27503] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:09 bi-first-server SM: [27503] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:09 bi-first-server SM: [27503] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:09 bi-first-server SM: [27577] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:32:09 bi-first-server SM: [27577] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:09 bi-first-server SM: [27577]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27577] Entering _checkMetadataVolume
Oct 31 15:32:09 bi-first-server SM: [27577] LVMCache: will initialize now
Oct 31 15:32:09 bi-first-server SM: [27577] LVMCache: refreshing
Oct 31 15:32:09 bi-first-server SM: [27577] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:09 bi-first-server SM: [27577]   pread SUCCESS
Oct 31 15:32:09 bi-first-server SM: [27577] vdi_detach {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|1c48bbc5-21a2-255b-9b28-ec866d0775bb|VDI.detach', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:8a6a7791-fb7f-2c0f-7bf0-13938c7396e0', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:32:09 bi-first-server SM: [27577] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:09 bi-first-server SM: [27577] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:09 bi-first-server SM: [27592] LVMCache created for VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:32:09 bi-first-server SM: [27592] ['/usr/sbin/vgs', 'VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:32:09 bi-first-server SM: [27592] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:32:09 bi-first-server SM: [27592] '
Oct 31 15:32:09 bi-first-server SM: [27592] LVMCache: will initialize now
Oct 31 15:32:09 bi-first-server SM: [27592] LVMCache: refreshing
Oct 31 15:32:09 bi-first-server SM: [27592] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044']
Oct 31 15:32:10 bi-first-server SM: [27592] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044" not found
Oct 31 15:32:10 bi-first-server SM: [27592]   Skipping volume group VG_XenStorage-bb66583f-2842-f789-6bc7-527355ce2044
Oct 31 15:32:10 bi-first-server SM: [27592] '
Oct 31 15:32:10 bi-first-server SM: [27592] vdi_detach {'sr_uuid': 'bb66583f-2842-f789-6bc7-527355ce2044', 'subtask_of': 'DummyRef:|ab9ef956-88f6-b004-c373-15764f3d7eac|VDI.detach', 'vdi_ref': 'OpaqueRef:a2693750-cccd-2638-3a4f-82f1757860df', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '79a6b627-6a67-4dfb-bf6f-8fea8b45489c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:81789e1c-245d-478e-2d7b-7ba81fe7767d', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de8400020c2761f0821895c', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:ad3f4289-7887-7858-c70e-182864fb1a73', 'vdi_uuid': '79a6b627-6a67-4dfb-bf6f-8fea8b45489c'}
Oct 31 15:32:10 bi-first-server SM: [27592] lock: closed /var/lock/sm/79a6b627-6a67-4dfb-bf6f-8fea8b45489c/vdi
Oct 31 15:32:10 bi-first-server SM: [27592] lock: closed /var/lock/sm/bb66583f-2842-f789-6bc7-527355ce2044/sr
Oct 31 15:32:10 bi-first-server SM: [27607] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] Entering _checkMetadataVolume
Oct 31 15:32:10 bi-first-server SM: [27607] LVMCache: will initialize now
Oct 31 15:32:10 bi-first-server SM: [27607] LVMCache: refreshing
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] vdi_deactivate {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|48dbc8e1-08a3-13b5-3d73-4d2080ef71f5|VDI.deactivate', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:53dac656-2268-f25f-d462-d46a2343832f', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_deactivate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:32:10 bi-first-server SM: [27607] blktap2.deactivate
Oct 31 15:32:10 bi-first-server SM: [27607] lock: acquired /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/tap-ctl', 'close', '-p', '27019', '-m', '20']
Oct 31 15:32:10 bi-first-server SM: [27607]  = 0
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/tap-ctl', 'detach', '-p', '27019', '-m', '20']
Oct 31 15:32:10 bi-first-server SM: [27607]  = 0
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/tap-ctl', 'free', '-m', '20']
Oct 31 15:32:10 bi-first-server SM: [27607]  = 0
Oct 31 15:32:10 bi-first-server SM: [27607] tap.deactivate: Shut down Tapdisk(vhd:/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c, pid=27019, minor=20, state=R)
Oct 31 15:32:10 bi-first-server SM: [27607] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] Entering _checkMetadataVolume
Oct 31 15:32:10 bi-first-server SM: [27607] LVMCache: will initialize now
Oct 31 15:32:10 bi-first-server SM: [27607] LVMCache: refreshing
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] LVHDVDI.detach for b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (0, 1) + (1, 0) => (1, 1)
Oct 31 15:32:10 bi-first-server SM: [27607] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (1, 1b)
Oct 31 15:32:10 bi-first-server SM: [27607] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-b7c4214a-11bc-479f-8289-e090cf61df6c', '-l', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868', '-a']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:32:10 bi-first-server SM: [27607] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (0, 1b)
Oct 31 15:32:10 bi-first-server SM: [27607] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: acquired /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c (0, 1) + (0, -1) => (0, 0)
Oct 31 15:32:10 bi-first-server SM: [27607] Refcount for lvm-d87cdd6c-d520-1250-637c-a3f42c8da868:b7c4214a-11bc-479f-8289-e090cf61df6c set => (0, 0b)
Oct 31 15:32:10 bi-first-server SM: [27607] ['/usr/sbin/lvchange', '-an', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868/VHD-b7c4214a-11bc-479f-8289-e090cf61df6c']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] ['/sbin/dmsetup', 'status', 'VG_XenStorage--d87cdd6c--d520--1250--637c--a3f42c8da868-VHD--b7c4214a--11bc--479f--8289--e090cf61df6c']
Oct 31 15:32:10 bi-first-server SM: [27607]   pread SUCCESS
Oct 31 15:32:10 bi-first-server SM: [27607] lock: released /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: closed /var/lock/sm/lvm-d87cdd6c-d520-1250-637c-a3f42c8da868/b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for b7c4214a-11bc-479f-8289-e090cf61df6c
Oct 31 15:32:10 bi-first-server SM: [27607] lock: released /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:32:10 bi-first-server SM: [27607] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:32:10 bi-first-server SM: [27607] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:32:10 bi-first-server SM: [27607] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:32:10 bi-first-server SM: [27664] LVMCache created for VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868
Oct 31 15:32:10 bi-first-server SM: [27664] ['/usr/sbin/vgs', 'VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:32:11 bi-first-server SM: [27664]   pread SUCCESS
Oct 31 15:32:11 bi-first-server SM: [27664] Entering _checkMetadataVolume
Oct 31 15:32:11 bi-first-server SM: [27664] LVMCache: will initialize now
Oct 31 15:32:11 bi-first-server SM: [27664] LVMCache: refreshing
Oct 31 15:32:11 bi-first-server SM: [27664] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-d87cdd6c-d520-1250-637c-a3f42c8da868']
Oct 31 15:32:11 bi-first-server SM: [27664]   pread SUCCESS
Oct 31 15:32:11 bi-first-server SM: [27664] vdi_detach {'sr_uuid': 'd87cdd6c-d520-1250-637c-a3f42c8da868', 'subtask_of': 'DummyRef:|7454abb9-a35d-8fcc-320c-f8ab9b2c0cd3|VDI.detach', 'vdi_ref': 'OpaqueRef:bb76a4f4-14cb-7214-76b0-3affa7b39fe1', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'b7c4214a-11bc-479f-8289-e090cf61df6c', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:4f93698b-20ef-b1a3-87f4-41a571790ddb', 'device_config': {'device': '/dev/disk/by-id/scsi-36001e67b0de840001c0164cc091fffb0-part3', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9eb61e09-9a00-f55b-8f1c-a47241a4ecb7', 'vdi_uuid': 'b7c4214a-11bc-479f-8289-e090cf61df6c'}
Oct 31 15:32:11 bi-first-server SM: [27664] lock: closed /var/lock/sm/b7c4214a-11bc-479f-8289-e090cf61df6c/vdi
Oct 31 15:32:11 bi-first-server SM: [27664] lock: closed /var/lock/sm/d87cdd6c-d520-1250-637c-a3f42c8da868/sr
Oct 31 15:32:22 bi-first-server SM: [27776] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:32:22 bi-first-server SM: [27776] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:22 bi-first-server SM: [27776]   pread SUCCESS
Oct 31 15:32:22 bi-first-server SM: [27776] lock: acquired /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:22 bi-first-server SM: [27776] LVMCache: will initialize now
Oct 31 15:32:22 bi-first-server SM: [27776] LVMCache: refreshing
Oct 31 15:32:22 bi-first-server SM: [27776] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:22 bi-first-server SM: [27776]   pread SUCCESS
Oct 31 15:32:22 bi-first-server SM: [27776] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:22 bi-first-server SM: [27776] Entering _checkMetadataVolume
Oct 31 15:32:22 bi-first-server SM: [27776] vdi_epoch_begin {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|adf1d235-17d0-f285-86ec-4ac3a780c56f|VDI.epoch_begin', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:2e902094-6b6f-2b45-73bd-a8b38137aadd', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_epoch_begin', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:32:22 bi-first-server SM: [27776] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:22 bi-first-server SM: [27791] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:32:22 bi-first-server SM: [27791] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:23 bi-first-server SM: [27791]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27791] Entering _checkMetadataVolume
Oct 31 15:32:23 bi-first-server SM: [27791] LVMCache: will initialize now
Oct 31 15:32:23 bi-first-server SM: [27791] LVMCache: refreshing
Oct 31 15:32:23 bi-first-server SM: [27791] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:23 bi-first-server SM: [27791]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27791] vdi_attach {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|6fb7a921-9d93-f9f2-6ae2-8f7b9d975e12|VDI.attach', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:393e728f-720e-d8b9-eb55-0c1084c2e169', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_attach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:32:23 bi-first-server SM: [27791] result: {'params': '/dev/sm/backend/bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240', 'xenstore_data': {'scsi/0x12/0x80': 'AIAAEjhhMDI5YTJjLWVmNDItNDkgIA==', 'scsi/0x12/0x83': 'AIMAMQIBAC1YRU5TUkMgIDhhMDI5YTJjLWVmNDItNDk3NS04YTBjLTE3MzFkN2I4ODI0MCA=', 'vdi-uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'mem-pool': 'bf62168d-e6c9-7e0a-cf76-821de372530d'}}
Oct 31 15:32:23 bi-first-server SM: [27791] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:23 bi-first-server SM: [27791] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:23 bi-first-server SM: [27817] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:23 bi-first-server SM: [27817]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27817] Entering _checkMetadataVolume
Oct 31 15:32:23 bi-first-server SM: [27817] LVMCache: will initialize now
Oct 31 15:32:23 bi-first-server SM: [27817] LVMCache: refreshing
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:23 bi-first-server SM: [27817]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27817] vdi_activate {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|66b5621b-4453-5520-9a80-93da86537e65|VDI.activate', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:0e15ba0e-59ed-2c39-f13e-44d7546c241f', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_activate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:32:23 bi-first-server SM: [27817] blktap2.activate
Oct 31 15:32:23 bi-first-server SM: [27817] lock: acquired /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:23 bi-first-server SM: [27817] Adding tag to: 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] Activate lock succeeded
Oct 31 15:32:23 bi-first-server SM: [27817] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:23 bi-first-server SM: [27817]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27817] Entering _checkMetadataVolume
Oct 31 15:32:23 bi-first-server SM: [27817] LVMCache: will initialize now
Oct 31 15:32:23 bi-first-server SM: [27817] LVMCache: refreshing
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:32:23 bi-first-server SM: [27817]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27817] LVHDVDI.attach for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (0, 0) + (1, 0) => (1, 0)
Oct 31 15:32:23 bi-first-server SM: [27817] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (1, 0b)
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/lvchange', '-ay', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:32:23 bi-first-server SM: [27817]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27817] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:32:23 bi-first-server SM: [27817]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27817] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240', '-l', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d', '-a']
Oct 31 15:32:23 bi-first-server SM: [27817]   pread SUCCESS
Oct 31 15:32:23 bi-first-server SM: [27817] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (1, 0) + (0, 1) => (1, 1)
Oct 31 15:32:23 bi-first-server SM: [27817] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (1, 1b)
Oct 31 15:32:23 bi-first-server SM: [27817] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:32:23 bi-first-server SM: [27817] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (0, 1b)
Oct 31 15:32:23 bi-first-server SM: [27817] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] PhyLink(/dev/sm/phy/bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240) -> /dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/tap-ctl', 'allocate']
Oct 31 15:32:23 bi-first-server SM: [27817]  = 0
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/tap-ctl', 'spawn']
Oct 31 15:32:23 bi-first-server SM: [27817]  = 0
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/tap-ctl', 'attach', '-p', '27877', '-m', '20']
Oct 31 15:32:23 bi-first-server SM: [27817]  = 0
Oct 31 15:32:23 bi-first-server SM: [27817] ['/usr/sbin/tap-ctl', 'open', '-p', '27877', '-m', '20', '-a', 'vhd:/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:32:23 bi-first-server SM: [27817]  = 0
Oct 31 15:32:23 bi-first-server SM: [27817] tap.activate: Launched Tapdisk(vhd:/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240, pid=27877, minor=20, state=R)
Oct 31 15:32:23 bi-first-server SM: [27817] DeviceNode(/dev/sm/backend/bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240) -> /dev/xen/blktap-2/tapdev20
Oct 31 15:32:23 bi-first-server SM: [27817] lock: released /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:23 bi-first-server SM: [27817] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:32:23 bi-first-server SM: [27817] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:32:23 bi-first-server SM: [27817] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:13 bi-first-server SM: [28533] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] Entering _checkMetadataVolume
Oct 31 15:33:13 bi-first-server SM: [28533] LVMCache: will initialize now
Oct 31 15:33:13 bi-first-server SM: [28533] LVMCache: refreshing
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] vdi_deactivate {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|f11fb255-f2e5-0a55-5a84-6ea8a2e55fff|VDI.deactivate', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:1343811a-d720-6c1e-106f-aded669c2266', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_deactivate', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:33:13 bi-first-server SM: [28533] blktap2.deactivate
Oct 31 15:33:13 bi-first-server SM: [28533] lock: acquired /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/tap-ctl', 'close', '-p', '27877', '-m', '20']
Oct 31 15:33:13 bi-first-server SM: [28533]  = 0
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/tap-ctl', 'detach', '-p', '27877', '-m', '20']
Oct 31 15:33:13 bi-first-server SM: [28533]  = 0
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/tap-ctl', 'free', '-m', '20']
Oct 31 15:33:13 bi-first-server SM: [28533]  = 0
Oct 31 15:33:13 bi-first-server SM: [28533] tap.deactivate: Shut down Tapdisk(vhd:/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240, pid=27877, minor=20, state=R)
Oct 31 15:33:13 bi-first-server SM: [28533] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] Entering _checkMetadataVolume
Oct 31 15:33:13 bi-first-server SM: [28533] LVMCache: will initialize now
Oct 31 15:33:13 bi-first-server SM: [28533] LVMCache: refreshing
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] LVHDVDI.detach for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (0, 1) + (1, 0) => (1, 1)
Oct 31 15:33:13 bi-first-server SM: [28533] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (1, 1b)
Oct 31 15:33:13 bi-first-server SM: [28533] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240', '-l', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d', '-a']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (1, 1) + (-1, 0) => (0, 1)
Oct 31 15:33:13 bi-first-server SM: [28533] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (0, 1b)
Oct 31 15:33:13 bi-first-server SM: [28533] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (0, 1) + (0, -1) => (0, 0)
Oct 31 15:33:13 bi-first-server SM: [28533] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (0, 0b)
Oct 31 15:33:13 bi-first-server SM: [28533] ['/usr/sbin/lvchange', '-an', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] ['/sbin/dmsetup', 'status', 'VG_XenStorage--bf62168d--e6c9--7e0a--cf76--821de372530d-VHD--8a029a2c--ef42--4975--8a0c--1731d7b88240']
Oct 31 15:33:13 bi-first-server SM: [28533]   pread SUCCESS
Oct 31 15:33:13 bi-first-server SM: [28533] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] Removed host key host_OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:13 bi-first-server SM: [28533] lock: released /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:33:13 bi-first-server SM: [28533] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:33:13 bi-first-server SM: [28533] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:13 bi-first-server SM: [28533] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:14 bi-first-server SM: [28636] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:33:14 bi-first-server SM: [28636] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:14 bi-first-server SM: [28636]   pread SUCCESS
Oct 31 15:33:14 bi-first-server SM: [28636] Entering _checkMetadataVolume
Oct 31 15:33:14 bi-first-server SM: [28636] LVMCache: will initialize now
Oct 31 15:33:14 bi-first-server SM: [28636] LVMCache: refreshing
Oct 31 15:33:14 bi-first-server SM: [28636] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:14 bi-first-server SM: [28636]   pread SUCCESS
Oct 31 15:33:14 bi-first-server SM: [28636] vdi_detach {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|d99d7d3d-abf4-2cf6-1e69-a7fe3e2fc24f|VDI.detach', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:8eab44a5-d95c-b005-283f-7fd94894ad5b', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:33:14 bi-first-server SM: [28636] lock: closed /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:33:14 bi-first-server SM: [28636] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SM: [28872] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:33:39 bi-first-server SM: [28872] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] lock: acquired /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SM: [28872] LVMCache: will initialize now
Oct 31 15:33:39 bi-first-server SM: [28872] LVMCache: refreshing
Oct 31 15:33:39 bi-first-server SM: [28872] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SM: [28872] Entering _checkMetadataVolume
Oct 31 15:33:39 bi-first-server SM: [28872] lock: acquired /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SM: [28872] vdi_delete {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'DummyRef:|98bb56a8-dc6f-082e-ebee-dd94fb4e987b|VDI.destroy', 'vdi_ref': 'OpaqueRef:e1f4efc6-28e3-1847-b643-466074248a8e', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': '8a029a2c-ef42-4975-8a0c-1731d7b88240', 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:1e5fd082-eda1-3606-e244-961a2f921882', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'vdi_delete', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6', 'vdi_uuid': '8a029a2c-ef42-4975-8a0c-1731d7b88240'}
Oct 31 15:33:39 bi-first-server SM: [28872] LVHDVDI.delete for 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (0, 0) + (1, 0) => (1, 0)
Oct 31 15:33:39 bi-first-server SM: [28872] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (1, 0b)
Oct 31 15:33:39 bi-first-server SM: [28872] ['/usr/sbin/lvchange', '-ay', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] ['/usr/bin/vhd-util', 'query', '--debug', '-vsf', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] ['/usr/bin/vhd-util', 'set', '--debug', '-n', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240', '-f', 'hidden', '-v', '1']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] Deleting vdi: 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] Entering deleteVdi
Oct 31 15:33:39 bi-first-server SM: [28872] entering updateVdi
Oct 31 15:33:39 bi-first-server SM: [28872] Entering getMetadataToWrite
Oct 31 15:33:39 bi-first-server SM: [28872] Entering VDI info
Oct 31 15:33:39 bi-first-server SM: [28872] Entering VDI info
Oct 31 15:33:39 bi-first-server SM: [28872] lock: acquired /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 (1, 0) + (-1, 0) => (0, 0)
Oct 31 15:33:39 bi-first-server SM: [28872] Refcount for lvm-bf62168d-e6c9-7e0a-cf76-821de372530d:8a029a2c-ef42-4975-8a0c-1731d7b88240 set => (0, 0b)
Oct 31 15:33:39 bi-first-server SM: [28872] ['/usr/sbin/lvchange', '-an', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] ['/sbin/dmsetup', 'status', 'VG_XenStorage--bf62168d--e6c9--7e0a--cf76--821de372530d-VHD--8a029a2c--ef42--4975--8a0c--1731d7b88240']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] lock: released /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] lock: closed /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:39 bi-first-server SM: [28872] ['/usr/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:39 bi-first-server SM: [28872]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28872] lock: tried lock /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/running, acquired: True (exists: True)
Oct 31 15:33:39 bi-first-server SM: [28872] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/running
Oct 31 15:33:39 bi-first-server SM: [28872] Kicking GC
Oct 31 15:33:39 bi-first-server SMGC: [28872] === SR bf62168d-e6c9-7e0a-cf76-821de372530d: gc ===
Oct 31 15:33:39 bi-first-server SMGC: [28910] Will finish as PID [28911]
Oct 31 15:33:39 bi-first-server SMGC: [28872] New PID [28910]
Oct 31 15:33:39 bi-first-server SM: [28872] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/running
Oct 31 15:33:39 bi-first-server SM: [28872] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SM: [28872] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SM: [28911] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:33:39 bi-first-server SM: [28911] lock: tried lock /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr, acquired: True (exists: True)
Oct 31 15:33:39 bi-first-server SM: [28911] LVMCache: refreshing
Oct 31 15:33:39 bi-first-server SM: [28911] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:39 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28911] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-*', '-l', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:39 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SMGC: [28911] SR bf62 ('Local storage3') (1 VDIs in 1 VHD trees): 
Oct 31 15:33:39 bi-first-server SMGC: [28911]         *8a029a2c[VHD](20.000G//20.047G|n)
Oct 31 15:33:39 bi-first-server SMGC: [28911] 
Oct 31 15:33:39 bi-first-server SM: [28911] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SM: [28911] lock: tried lock /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/running, acquired: True (exists: True)
Oct 31 15:33:39 bi-first-server SM: [28911] lock: tried lock /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr, acquired: True (exists: True)
Oct 31 15:33:39 bi-first-server SM: [28911] LVMCache: refreshing
Oct 31 15:33:39 bi-first-server SM: [28911] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:39 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SM: [28911] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', 'VHD-*', '-l', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:39 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:39 bi-first-server SMGC: [28911] SR bf62 ('Local storage3') (1 VDIs in 1 VHD trees): no changes
Oct 31 15:33:39 bi-first-server SM: [28911] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:39 bi-first-server SMGC: [28911] Found 1 VDIs for deletion:
Oct 31 15:33:39 bi-first-server SMGC: [28911]   *8a029a2c[VHD](20.000G//20.047G|n)
Oct 31 15:33:39 bi-first-server SMGC: [28911] Deleting unlinked VDI *8a029a2c[VHD](20.000G//20.047G|n)
Oct 31 15:33:40 bi-first-server SM: [28911] lock: tried lock /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr, acquired: True (exists: True)
Oct 31 15:33:40 bi-first-server SM: [28911] ['/usr/sbin/lvremove', '-f', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d/VHD-8a029a2c-ef42-4975-8a0c-1731d7b88240']
Oct 31 15:33:40 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:40 bi-first-server SM: [28911] ['/sbin/dmsetup', 'status', 'VG_XenStorage--bf62168d--e6c9--7e0a--cf76--821de372530d-VHD--8a029a2c--ef42--4975--8a0c--1731d7b88240']
Oct 31 15:33:40 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:40 bi-first-server SM: [28911] Deleting vdi: 8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:40 bi-first-server SM: [28911] Entering deleteVdi
Oct 31 15:33:40 bi-first-server SM: [28911] Metadata for VDI 8a029a2c-ef42-4975-8a0c-1731d7b88240 not present, or already removed, no further deletion action required.
Oct 31 15:33:40 bi-first-server SM: [28911] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:40 bi-first-server SM: [28911] lock: unlinking lock file /var/lock/sm/lvm-bf62168d-e6c9-7e0a-cf76-821de372530d/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:40 bi-first-server SM: [28911] lock: unlinking lock file /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240/vdi
Oct 31 15:33:40 bi-first-server SM: [28911] lock: removing lock dir /var/lock/sm/8a029a2c-ef42-4975-8a0c-1731d7b88240
Oct 31 15:33:40 bi-first-server SMGC: [28911] Starting asynch srUpdate for SR bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:33:40 bi-first-server SM: [28962] LVMCache created for VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d
Oct 31 15:33:40 bi-first-server SM: [28962] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:40 bi-first-server SM: [28962]   pread SUCCESS
Oct 31 15:33:40 bi-first-server SM: [28962] lock: acquired /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:40 bi-first-server SM: [28962] LVMCache: will initialize now
Oct 31 15:33:40 bi-first-server SM: [28962] LVMCache: refreshing
Oct 31 15:33:40 bi-first-server SM: [28962] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:40 bi-first-server SM: [28962]   pread SUCCESS
Oct 31 15:33:40 bi-first-server SM: [28962] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:40 bi-first-server SM: [28962] Entering _checkMetadataVolume
Oct 31 15:33:40 bi-first-server SM: [28962] lock: acquired /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:40 bi-first-server SM: [28962] sr_update {'sr_uuid': 'bf62168d-e6c9-7e0a-cf76-821de372530d', 'subtask_of': 'OpaqueRef:ebe3665d-99b7-ed6e-4350-a1a7cd6ae44f', 'args': [], 'host_ref': 'OpaqueRef:fd3479ad-ea24-6f49-1698-691450b4a25f', 'session_ref': 'OpaqueRef:39194db5-b297-4c2f-a164-5bbf83ddb4bf', 'device_config': {'device': '/dev/sdc', 'SRmaster': 'true'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:9a6cf520-6681-87f1-8901-e3da9c779ef6'}
Oct 31 15:33:40 bi-first-server SM: [28962] ['/usr/sbin/vgs', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:40 bi-first-server SM: [28962]   pread SUCCESS
Oct 31 15:33:40 bi-first-server SM: [28962] ['/usr/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:40 bi-first-server SM: [28962]   pread SUCCESS
Oct 31 15:33:40 bi-first-server SM: [28962] Updating metadata : {'objtype': 'sr', 'name_description': 'Local storage3', 'name_label': 'Local storage3'}
Oct 31 15:33:40 bi-first-server SM: [28962] entering updateSR
Oct 31 15:33:40 bi-first-server SM: [28962] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:40 bi-first-server SM: [28962] lock: closed /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:41 bi-first-server SMGC: [28911] SR.update_asynch status changed to [success]
Oct 31 15:33:41 bi-first-server SM: [28911] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/running
Oct 31 15:33:41 bi-first-server SM: [28911] lock: tried lock /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr, acquired: True (exists: True)
Oct 31 15:33:41 bi-first-server SM: [28911] LVMCache: refreshing
Oct 31 15:33:41 bi-first-server SM: [28911] ['/usr/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:41 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:41 bi-first-server SMGC: [28911] SR bf62 ('Local storage3') (0 VDIs in 0 VHD trees): showing only VHD trees that changed:
Oct 31 15:33:41 bi-first-server SMGC: [28911] Tree 8a029a2c-ef42-4975-8a0c-1731d7b88240 gone
Oct 31 15:33:41 bi-first-server SMGC: [28911] 
Oct 31 15:33:41 bi-first-server SM: [28911] lock: released /var/lock/sm/bf62168d-e6c9-7e0a-cf76-821de372530d/sr
Oct 31 15:33:41 bi-first-server SM: [28911] ['/usr/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:41 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:41 bi-first-server SM: [28911] ['/usr/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-bf62168d-e6c9-7e0a-cf76-821de372530d']
Oct 31 15:33:41 bi-first-server SM: [28911]   pread SUCCESS
Oct 31 15:33:41 bi-first-server SMGC: [28911] No work, exiting
Oct 31 15:33:41 bi-first-server SMGC: [28911] SR bf62 ('Local storage3') (0 VDIs in 0 VHD trees): no changes

 

Link to comment
  • 0

Don't see any input/output errors so short of a restart there may not be much 

you can do. If a restart doesn't work then it will be restoring your VM from 

backups. There should be a way to ask XenServer to do an fsck on the next

restart if you think that is a possible root issue. I am assuming its just touching

a file like most other Linux servers.

 

--Alan--

 

Link to comment

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
×
×
  • Create New...