Apr 29 12:52:19 primary-xen-bms-jlr SM: [2689] Warning: vdi_[de]activate present for dummy
Apr 29 12:52:22 primary-xen-bms-jlr root: Fixing refcounts on new master:
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] sr_attach {'sr_uuid': 'a399db02-45b1-65fb-1543-085f00a03423', 'subtask_of': 'DummyRef:|01f76f16-c36f-4e7b-a501-7393498cea11|SR.attach', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:dafc1213-3a5a-4ea4-8132-de8282702fd3', 'device_config': {'path': '/opt/xensource/packages/iso', 'legacy_mode': 'true', 'SRmaster': 'true', 'location': '/opt/xensource/packages/iso'}, 'command': 'sr_attach', 'sr_ref': 'OpaqueRef:a40bf584-8831-48b7-8b1a-79664c3fb29f'}
Apr 29 12:52:22 primary-xen-bms-jlr SMGC: [3133] === SR a399db02-45b1-65fb-1543-085f00a03423: abort ===
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: opening lock file /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/running
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: opening lock file /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/gc_active
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: tried lock /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/gc_active, acquired: True (exists: True)
Apr 29 12:52:22 primary-xen-bms-jlr SMGC: [3133] abort: releasing the process lock
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: released /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/gc_active
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: opening lock file /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/sr
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: acquired /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/running
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: acquired /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/sr
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] RESET for SR a399db02-45b1-65fb-1543-085f00a03423 (master: True)
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: released /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/sr
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] lock: released /var/lock/sm/a399db02-45b1-65fb-1543-085f00a03423/running
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3133] set_dirty 'OpaqueRef:a40bf584-8831-48b7-8b1a-79664c3fb29f' succeeded
Apr 29 12:52:22 primary-xen-bms-jlr SM: [3152] sr_update {'sr_uuid': 'a399db02-45b1-65fb-1543-085f00a03423', 'subtask_of': 'DummyRef:|6564adfc-2409-4e2a-8bf1-22a3f33cb463|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:3feb9810-7011-40c3-b6b2-24ba02c9eda5', 'device_config': {'path': '/opt/xensource/packages/iso', 'legacy_mode': 'true', 'SRmaster': 'true', 'location': '/opt/xensource/packages/iso'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:a40bf584-8831-48b7-8b1a-79664c3fb29f'}
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3240] MPATH: Update done
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] FAILED in util.pread: (rc 5) stdout: '', stderr: '  /dev/sdc: open failed: No medium found
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   Volume group "VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61" not found
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   Cannot process volume group VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] '
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] LVMCache: will initialize now
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] LVMCache: refreshing
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] FAILED in util.pread: (rc 5) stdout: '', stderr: '  /dev/sdc: open failed: No medium found
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   Volume group "VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61" not found
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   Cannot process volume group VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] '
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] sr_create {'sr_uuid': '597048cd-79c6-c862-3abe-96df74908d61', 'subtask_of': 'DummyRef:|cbc0d574-5bc1-478f-919c-179e16cd9d69|SR.create', 'args': ['0'], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:19a23604-0bd7-4934-b49e-550258d4b731', 'device_config': {'device': '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'SRmaster': 'true'}, 'command': 'sr_create', 'sr_ref': 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183'}
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] LVHDSR.create for 597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] FAILED in util.pread: (rc 5) stdout: '', stderr: '  /dev/sdc: open failed: No medium found
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   Volume group "VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61" not found
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   Cannot process volume group VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] '
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] util.test_scsiserial: Not a serial device: /dev/nvme0n1
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] ['/bin/dd', 'if=/dev/zero', 'of=/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'bs=1M', 'count=10', 'oflag=direct']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] ['/sbin/vgcreate', '--metadatasize', '10M', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61', '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] ['/sbin/vgchange', '-an', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] FAILED in util.pread: (rc 1) stdout: '', stderr: ''
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3403] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] LVMCache: will initialize now
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] LVMCache: refreshing
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] sr_attach {'sr_uuid': '597048cd-79c6-c862-3abe-96df74908d61', 'subtask_of': 'DummyRef:|8efc1630-ace2-41c5-bb67-5b795d1c3ee6|SR.attach', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:4444bb44-732a-4301-acdf-6c6f23ca4256', 'device_config': {'device': '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'SRmaster': 'true'}, 'command': 'sr_attach', 'sr_ref': 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183'}
Apr 29 12:52:23 primary-xen-bms-jlr SMGC: [3544] === SR 597048cd-79c6-c862-3abe-96df74908d61: abort ===
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: tried lock /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active, acquired: True (exists: True)
Apr 29 12:52:23 primary-xen-bms-jlr SMGC: [3544] abort: releasing the process lock
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] RESET for SR 597048cd-79c6-c862-3abe-96df74908d61 (master: True)
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] set_dirty 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183' succeeded
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] LVHDSR.attach for 597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] Entering _checkMetadataVolume
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] Creating Metadata volume
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/lvcreate', '-n', 'MGT', '-L', '4', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61', '-W', 'n']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61/MGT']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] Calling devlist_to_serial
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] FAILED in util.pread: (rc 1) stdout: '', stderr: ''
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] Dom0 disks: ['/dev/sdb']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] Block scheduler: /dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K (/dev/nvme0n) wants noop
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] no path /sys/block/nvme0n/queue/scheduler
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] LVHDSR.scan for 597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] LVMCache: refreshing
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:23 primary-xen-bms-jlr SM: [3544] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] lock: tried lock /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running, acquired: True (exists: True)
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] Kicking GC
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3544] === SR 597048cd-79c6-c862-3abe-96df74908d61: gc ===
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3827] Will finish as PID [3830]
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3830] lock: closed /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3544] New PID [3827]
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3544] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3830] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830] *~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]          ***********************
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]          *  E X C E P T I O N  *
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]          ***********************
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830] gc: EXCEPTION <class 'util.SMException'>, SR 597048cd-79c6-c862-3abe-96df74908d61 not attached on this host
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]   File "/opt/xensource/sm/cleanup.py", line 3379, in gc
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]     _gc(None, srUuid, dryRun)
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]   File "/opt/xensource/sm/cleanup.py", line 3258, in _gc
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]     sr = SR.getInstance(srUuid, session)
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]   File "/opt/xensource/sm/cleanup.py", line 1554, in getInstance
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]     return LVHDSR(uuid, xapi, createLock, force)
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]   File "/opt/xensource/sm/cleanup.py", line 2561, in __init__
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]     SR.__init__(self, uuid, xapi, createLock, force)
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]   File "/opt/xensource/sm/cleanup.py", line 1582, in __init__
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]     raise util.SMException("SR %s not attached on this host" % uuid)
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830] *~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830] * * * * * SR 597048cd-79c6-c862-3abe-96df74908d61: ERROR
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3830]
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] LVMCache: will initialize now
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] LVMCache: refreshing
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] Entering _checkMetadataVolume
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] sr_update {'sr_uuid': '597048cd-79c6-c862-3abe-96df74908d61', 'subtask_of': 'DummyRef:|e6fa77c8-d730-49f1-a0da-03a96e381e6a|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:5441cb60-f310-4e70-859b-4e0b17732dc2', 'device_config': {'device': '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'SRmaster': 'true'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183'}
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] Setting virtual_allocation of SR 597048cd-79c6-c862-3abe-96df74908d61 to 0
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] Updating metadata : {'objtype': 'sr', 'name_description': '', 'name_label': 'Local storage'}
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] entering updateSR
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3864] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] LVMCache: will initialize now
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] LVMCache: refreshing
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] Entering _checkMetadataVolume
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] sr_scan {'sr_uuid': '597048cd-79c6-c862-3abe-96df74908d61', 'subtask_of': 'DummyRef:|3b66ea3b-7d4e-424c-923b-e4d104a4e15b|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:a73dff1a-6b4a-46b4-a808-a9703d716068', 'device_config': {'device': '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'SRmaster': 'true'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183'}
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] LVHDSR.scan for 597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] LVMCache: refreshing
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4009] sr_create {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|986c28fd-44c0-4e80-9b13-10013e29bf8f|SR.create', 'args': ['0'], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:03889e13-4a8a-4146-a3bd-d867cd9daf42', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_create', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: tried lock /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running, acquired: True (exists: True)
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] Kicking GC
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3918] === SR 597048cd-79c6-c862-3abe-96df74908d61: gc ===
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [4070] Will finish as PID [4075]
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: closed /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [3918] New PID [4070]
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active
Apr 29 12:52:24 primary-xen-bms-jlr SM: [3918] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: tried lock /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active, acquired: True (exists: True)
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: tried lock /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr, acquired: True (exists: True)
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] LVMCache: refreshing
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [4075] SR 5970 ('Local storage') (0 VDIs in 0 VHD trees): no changes
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] sr_attach {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|52830a4d-958d-4509-b04f-02b8fc4cc47b|SR.attach', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:65f75de1-fdbb-4b45-8800-1613259ac334', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_attach', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [4096] === SR 924c2810-bbb1-8a62-7ff7-6e650d3639d5: abort ===
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: opening lock file /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/running
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: opening lock file /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/gc_active
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: tried lock /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/gc_active, acquired: True (exists: True)
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [4096] abort: releasing the process lock
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: released /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/gc_active
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: opening lock file /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: acquired /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/running
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: acquired /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] RESET for SR 924c2810-bbb1-8a62-7ff7-6e650d3639d5 (master: True)
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: released /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] lock: released /var/lock/sm/924c2810-bbb1-8a62-7ff7-6e650d3639d5/running
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] set_dirty 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9' succeeded
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [4075] No work, exiting
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [4075] GC process exiting, no work left
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4075] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active
Apr 29 12:52:24 primary-xen-bms-jlr SMGC: [4075] SR 5970 ('Local storage') (0 VDIs in 0 VHD trees): no changes
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] new VDIs on disk: set(['/dev/xapi/block/sdc'])
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] Introducing VDI with location=/dev/xapi/block/sdc
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096] ['uuidgen', '-r']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4096]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] LVMCache: will initialize now
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] LVMCache: refreshing
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] Entering _checkMetadataVolume
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] sr_update {'sr_uuid': '597048cd-79c6-c862-3abe-96df74908d61', 'subtask_of': 'DummyRef:|0c9dcccd-f438-408e-aff2-9a79246dcd26|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:ed249a5a-d887-44f5-937a-46f4053968dc', 'device_config': {'device': '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'SRmaster': 'true'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183'}
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] Setting virtual_allocation of SR 597048cd-79c6-c862-3abe-96df74908d61 to 0
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4206] sr_update {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|092988d6-9122-4ebc-a2e6-8d1ba513b187|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:024085ca-cd31-4d8f-a7dd-fac148a6e3e8', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122]   pread SUCCESS
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] Updating metadata : {'objtype': 'sr', 'name_description': '', 'name_label': 'Local storage'}
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] entering updateSR
Apr 29 12:52:24 primary-xen-bms-jlr SM: [4122] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4236] sr_scan {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|a7649110-050e-4f0e-b092-1e9795cd6c39|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:68a3b741-b100-4471-b924-acd54c44d71a', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242]   pread SUCCESS
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] LVMCache: will initialize now
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] LVMCache: refreshing
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4270] sr_create {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|ef678827-183b-4a5d-b9a7-739251875af0|SR.create', 'args': ['0'], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:6ee5179b-8752-42a0-89f1-c0daa3ff739f', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_create', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242]   pread SUCCESS
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] Entering _checkMetadataVolume
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] sr_update {'sr_uuid': '597048cd-79c6-c862-3abe-96df74908d61', 'subtask_of': 'DummyRef:|ea87b2a1-76b4-466d-a8b4-95efeb1efa9d|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:2e8df5db-b7b3-41f2-b75d-4787fb1b09e3', 'device_config': {'device': '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'SRmaster': 'true'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4292] sr_update {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|0d039201-4c93-48fb-bd58-144768dc142e|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:899aa6f2-4a57-4e31-862a-a8c361ef6cc2', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242]   pread SUCCESS
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] Setting virtual_allocation of SR 597048cd-79c6-c862-3abe-96df74908d61 to 0
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242]   pread SUCCESS
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: released /var/lock/sm/.nil/lvm
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] Updating metadata : {'objtype': 'sr', 'name_description': '', 'name_label': 'Local storage'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] entering updateSR
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4242] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] sr_attach {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|ed1bc6a4-f498-445d-abc1-168209b2668b|SR.attach', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:8695d558-743f-439b-8a92-a5fb4e8f4a70', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_attach', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 12:52:25 primary-xen-bms-jlr SMGC: [4342] === SR f24b682e-74f7-b170-cdf4-877da00a2e60: abort ===
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: opening lock file /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/running
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: opening lock file /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/gc_active
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: tried lock /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/gc_active, acquired: True (exists: True)
Apr 29 12:52:25 primary-xen-bms-jlr SMGC: [4342] abort: releasing the process lock
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: released /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/gc_active
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: opening lock file /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: acquired /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/running
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: acquired /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] RESET for SR f24b682e-74f7-b170-cdf4-877da00a2e60 (master: True)
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: released /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/sr
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] lock: released /var/lock/sm/f24b682e-74f7-b170-cdf4-877da00a2e60/running
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] set_dirty 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338' succeeded
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] new VDIs on disk: set(['/dev/xapi/cd/sr0'])
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] Introducing VDI with location=/dev/xapi/cd/sr0
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342] ['uuidgen', '-r']
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4351] sr_update {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|23f3e13a-94ed-4da7-badb-1107d21652e5|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:82b376b5-6621-4a0c-ae97-c0fa4a25c61d', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4342]   pread SUCCESS
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4427] sr_update {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|fb4f2dd4-0977-4b73-9a6d-491791fc8227|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:fa31d063-9558-46ee-a862-3ee538462501', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4489] sr_scan {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|7c953703-83e5-4f3e-89ac-f7b1d3a90c08|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:b501b12c-cf44-47ba-a7e5-86e62c9566ce', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4565] sr_update {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|08cb12c0-bbae-49ee-9152-886a959b0ae5|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:6eaae92b-f591-47cb-a568-a6563b001f5d', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4592] sr_update {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|12c0f93f-1716-4c6f-afa3-e64ecc4fd300|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:18fbc044-eef5-4f98-9da4-a6c83955541c', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 12:52:25 primary-xen-bms-jlr SM: [4649] sr_update {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|139d4a33-0b1c-49c6-88e1-2c653d91afec|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:510a0a84-c807-4e69-9a3a-9b50d8571c76', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5328] sr_scan {'sr_uuid': 'a399db02-45b1-65fb-1543-085f00a03423', 'subtask_of': 'DummyRef:|697651c1-54dc-482e-8792-81d02ea013a0|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:8a94a87c-5e41-4167-8c24-a5ca71c29c80', 'device_config': {'path': '/opt/xensource/packages/iso', 'legacy_mode': 'true', 'SRmaster': 'true', 'location': '/opt/xensource/packages/iso'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:a40bf584-8831-48b7-8b1a-79664c3fb29f'}
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5328] version=8.2.0 build=9.xcpng8.2
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5328] new VDIs on disk: set(['guest-tools-8.2.0-9.xcpng8.2.iso'])
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5328] Introducing VDI with location=guest-tools-8.2.0-9.xcpng8.2.iso
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5328] ['uuidgen', '-r']
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5328]   pread SUCCESS
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5350] version=8.2.0 build=9.xcpng8.2
Apr 29 12:52:52 primary-xen-bms-jlr SM: [5350] vdi_update {'sr_uuid': 'a399db02-45b1-65fb-1543-085f00a03423', 'subtask_of': 'DummyRef:|208c55dc-447a-4f69-b2a0-e09427c66360|VDI.stat', 'vdi_ref': 'OpaqueRef:ce006950-d4cb-4aef-9eff-40c858871db9', 'vdi_on_boot': 'persist', 'args': [], 'vdi_location': 'guest-tools-8.2.0-9.xcpng8.2.iso', 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:de40cde9-ce7d-484d-8876-22b92e96f209', 'device_config': {'path': '/opt/xensource/packages/iso', 'legacy_mode': 'true', 'SRmaster': 'true', 'location': '/opt/xensource/packages/iso'}, 'command': 'vdi_update', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:a40bf584-8831-48b7-8b1a-79664c3fb29f', 'vdi_uuid': '532900da-dc9a-4296-b002-6bad62ed0779'}
Apr 29 12:52:53 primary-xen-bms-jlr SM: [5363] sr_update {'sr_uuid': 'a399db02-45b1-65fb-1543-085f00a03423', 'subtask_of': 'DummyRef:|5710ece0-3ca1-4e26-b038-75faee31aceb|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:04073c2d-4700-42fc-87c5-46fd59b34413', 'device_config': {'path': '/opt/xensource/packages/iso', 'legacy_mode': 'true', 'SRmaster': 'true', 'location': '/opt/xensource/packages/iso'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:a40bf584-8831-48b7-8b1a-79664c3fb29f'}
Apr 29 13:00:52 primary-xen-bms-jlr SM: [7794] sr_scan {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|6e90a041-a9f9-45c4-a10c-4e281d2b1b2d|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:9e9d812a-f99d-4411-8408-1e7330ba2e2b', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 13:00:52 primary-xen-bms-jlr SM: [7836] sr_scan {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|91fa96b6-e9dc-49da-8d85-772f375f1d7b|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:d31e8fb7-233b-427a-8252-bb3c5716e2b3', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 13:00:52 primary-xen-bms-jlr SM: [7853] sr_update {'sr_uuid': 'f24b682e-74f7-b170-cdf4-877da00a2e60', 'subtask_of': 'DummyRef:|d4d859e7-eb90-432a-94b9-4e8c868123d0|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:4d5548f7-c4a5-4fc6-a5bd-80e085a18ea1', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/cd'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:813d34ee-bda2-4496-8240-774553ad8338'}
Apr 29 13:00:52 primary-xen-bms-jlr SM: [7878] sr_update {'sr_uuid': '924c2810-bbb1-8a62-7ff7-6e650d3639d5', 'subtask_of': 'DummyRef:|b0d51825-9174-4c5b-ac45-ffd90d9b6a2c|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:807765b7-98c2-44c1-9701-a9f472109c17', 'device_config': {'SRmaster': 'true', 'location': '/dev/xapi/block'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:9ff98a04-aed5-45aa-8b11-6f42fef353a9'}
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] LVMCache created for VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] ['/sbin/vgs', '--readonly', 'VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869]   pread SUCCESS
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] LVMCache: will initialize now
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] LVMCache: refreshing
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-597048cd-79c6-c862-3abe-96df74908d61']
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869]   pread SUCCESS
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] Entering _checkMetadataVolume
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: acquired /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] sr_detach {'sr_uuid': '597048cd-79c6-c862-3abe-96df74908d61', 'subtask_of': 'DummyRef:|82a24b66-8d86-4820-9fbf-e9ac7c9ebf36|SR.detach', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:db63f999-b040-48de-945f-bbfd9bb2ce8f', 'device_config': {'device': '/dev/disk/by-id/nvme-Samsung_SSD_990_PRO_4TB_S7DPNJ0X126050K', 'SRmaster': 'true'}, 'command': 'sr_detach', 'sr_ref': 'OpaqueRef:7e060c81-d7c9-4918-8e80-73b89b6ce183'}
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] LVHDSR.detach for 597048cd-79c6-c862-3abe-96df74908d61
Apr 29 13:20:25 primary-xen-bms-jlr SMGC: [15869] === SR 597048cd-79c6-c862-3abe-96df74908d61: abort ===
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/running
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: opening lock file /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: tried lock /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active, acquired: True (exists: True)
Apr 29 13:20:25 primary-xen-bms-jlr SMGC: [15869] abort: releasing the process lock
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/gc_active
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] Entering doesFileHaveOpenHandles with file: /dev/mapper/VG_XenStorage--597048cd--79c6--c862--3abe--96df74908d61-MGT
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] Entering findRunningProcessOrOpenFile with params: ['/dev/mapper/VG_XenStorage--597048cd--79c6--c862--3abe--96df74908d61-MGT', False]
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] ['/sbin/dmsetup', 'remove', '/dev/mapper/VG_XenStorage--597048cd--79c6--c862--3abe--96df74908d61-MGT']
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869]   pread SUCCESS
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:20:25 primary-xen-bms-jlr SM: [15869] lock: released /var/lock/sm/597048cd-79c6-c862-3abe-96df74908d61/sr
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] Raising exception [96, The request is missing or has an incorrect target IQN parameter]
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The request is missing or has an incorrect target IQN parameter
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]     self.load(sr_uuid)
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]   File "/opt/xensource/sm/LVMoISCSISR", line 86, in load
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]     iscsi = BaseISCSI.BaseISCSISR(self.original_srcmd, sr_uuid)
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]     self.load(sr_uuid)
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]   File "/opt/xensource/sm/BaseISCSI.py", line 212, in load
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]     raise xs_errors.XenError('ConfigTargetIQNMissing')
Apr 29 13:21:58 primary-xen-bms-jlr SM: [19739]
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] ]]
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] ]]
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] ]]
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] Logging in to [10.10.10.3:3260]
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:02 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['11']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] IQN match. Incrementing sessions to 1
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] LUNprint: waiting for path: /dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] adapter=11
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['/usr/bin/sg_luns', '-q', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['/usr/bin/sg_scan']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] Got all 2 sg devices
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['/sbin/udevadm', 'settle']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] Dom0 disks: ['/dev/sdb']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['ls', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', '-1', '--color=never']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['uuidgen', '-r']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['md5sum']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread3 SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['sginfo', '-M', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['sginfo', '-s', '/dev/sdc']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173]   pread SUCCESS
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:07 primary-xen-bms-jlr SM: [20173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173] ]]
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173] Raising exception [107, The SCSIid parameter is missing or incorrect]
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The SCSIid parameter is missing or incorrect
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173]     self.load(sr_uuid)
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173]   File "/opt/xensource/sm/LVMoISCSISR", line 190, in load
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173]     raise xs_errors.XenError('ConfigSCSIid')
Apr 29 13:22:08 primary-xen-bms-jlr SM: [20173]
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] ['uuidgen', '-r']
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876]   pread SUCCESS
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: opening lock file /var/lock/sm/sr
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] LVMCache created for VG_XenStorage-656bf61c-b03d-49ec-8401-761dbd31de01
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] sr_probe {'subtask_of': 'DummyRef:|c06c83fe-8998-41e9-8064-27600d01d24e|SR.probe', 'sr_sm_config': {}, 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:da09fb3c-bba2-408c-9c3c-8d674672b8d4', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e0000000001000a', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_probe'}
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] ['uuidgen', '-r']
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876]   pread SUCCESS
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] ]]
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] ]]
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] Logging in to [10.10.10.3:3260]
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] Keep the current replacement_timout value: 144.
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:11 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:16 primary-xen-bms-jlr SM: [20876] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:22:16 primary-xen-bms-jlr SM: [20876]   pread SUCCESS
Apr 29 13:22:16 primary-xen-bms-jlr SM: [20876] ['11']
Apr 29 13:22:16 primary-xen-bms-jlr SM: [20876] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:22:16 primary-xen-bms-jlr SM: [20876] IQN match. Incrementing sessions to 1
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] Set scheduler to [noop] on [/sys/block/sdc]
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: opening lock file /var/lock/sm/c2a205be-ce94-4c3d-90b3-0d6f7e5ffeb9/sr
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] LVMCache created for VG_XenStorage-c2a205be-ce94-4c3d-90b3-0d6f7e5ffeb9
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] ['/sbin/pvs', '--noheadings', '-o', 'vg_name', '/dev/disk/by-id/scsi-360000000000000000e0000000001000a']
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Failed to find physical volume "/dev/sdc".
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] '
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] ]]
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:17 primary-xen-bms-jlr SM: [20876] sr_probe result: <?xml version="1.0" ?><SRlist/>
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: opening lock file /var/lock/sm/1a3defcd-6477-2912-f7a2-1eaa115d5024/sr
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] LVMCache created for VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] ['/sbin/vgs', '--readonly', 'VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024']
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024" not found
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642]   Cannot process volume group VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] '
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] LVMCache: will initialize now
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] LVMCache: refreshing
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024']
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024" not found
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642]   Cannot process volume group VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] '
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/1a3defcd-6477-2912-f7a2-1eaa115d5024/sr
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] sr_create {'sr_uuid': '1a3defcd-6477-2912-f7a2-1eaa115d5024', 'subtask_of': 'DummyRef:|d6dcc2a2-f883-4355-bbde-3c72d5bd3f89|SR.create', 'args': ['0'], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:8cb35b89-63e8-47cf-ac0f-c430d6869f49', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e0000000001000a', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_create', 'sr_ref': 'OpaqueRef:c8063ceb-2882-41aa-99d3-513abaeddb2c'}
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] ]]
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] ]]
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] Logging in to [10.10.10.3:3260]
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] Keep the current replacement_timout value: 144.
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:21 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   pread SUCCESS
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['11']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] IQN match. Incrementing sessions to 1
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] Set scheduler to [noop] on [/sys/block/sdc]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] LVMCache created for VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['/sbin/vgs', '--readonly', 'VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024" not found
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   Cannot process volume group VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] '
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] LVMCache: will initialize now
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] LVMCache: refreshing
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024" not found
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   Cannot process volume group VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] '
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] LVHDSR.create for 1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['/sbin/vgs', '--readonly', 'VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024" not found
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   Cannot process volume group VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] '
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['sginfo', '-s', '/dev/sdc']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   pread SUCCESS
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/sdc']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   pread SUCCESS
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['/bin/dd', 'if=/dev/zero', 'of=/dev/disk/by-id/scsi-360000000000000000e0000000001000a', 'bs=1M', 'count=10', 'oflag=direct']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   pread SUCCESS
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['/sbin/vgcreate', '--metadatasize', '10M', 'VG_XenStorage-1a3defcd-6477-2912-f7a2-1eaa115d5024', '/dev/disk/by-id/scsi-360000000000000000e0000000001000a']
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] FAILED in util.pread: (rc 5) stdout: '', stderr: 'WARNING: drbd signature detected on /dev/disk/by-id/scsi-360000000000000000e0000000001000a at offset 4000664891452. Wipe it? [y/n]: [n]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   Aborted wiping of drbd.
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   1 existing signature left on the device.
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] '
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] Raising exception [77, Logical Volume group creation failed]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ]]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] Raising exception [47, The SR is not available [opterr=Logical Volume group creation failed]]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] lock: released /var/lock/sm/1a3defcd-6477-2912-f7a2-1eaa115d5024/sr
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ***** generic exception: sr_create: EXCEPTION <class 'SR.SROSError'>, The SR is not available [opterr=Logical Volume group creation failed]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/SRCommand.py", line 110, in run
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     return self._run_locked(sr)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/SRCommand.py", line 159, in _run_locked
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     rv = self._run(sr, target)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/SRCommand.py", line 323, in _run
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     return sr.create(self.params['sr_uuid'], long(self.params['args'][0]))
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/LVMoISCSISR", line 471, in create
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     raise xs_errors.XenError("SRUnavailable", opterr=inst)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The SR is not available [opterr=Logical Volume group creation failed]
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/SRCommand.py", line 378, in run
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     ret = cmd.run(sr)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/SRCommand.py", line 110, in run
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     return self._run_locked(sr)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/SRCommand.py", line 159, in _run_locked
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     rv = self._run(sr, target)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/SRCommand.py", line 323, in _run
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     return sr.create(self.params['sr_uuid'], long(self.params['args'][0]))
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]   File "/opt/xensource/sm/LVMoISCSISR", line 471, in create
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]     raise xs_errors.XenError("SRUnavailable", opterr=inst)
Apr 29 13:22:26 primary-xen-bms-jlr SM: [21642]
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] Raising exception [96, The request is missing or has an incorrect target IQN parameter]
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The request is missing or has an incorrect target IQN parameter
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]     self.load(sr_uuid)
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]   File "/opt/xensource/sm/LVMoISCSISR", line 86, in load
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]     iscsi = BaseISCSI.BaseISCSISR(self.original_srcmd, sr_uuid)
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]     self.load(sr_uuid)
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]   File "/opt/xensource/sm/BaseISCSI.py", line 212, in load
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]     raise xs_errors.XenError('ConfigTargetIQNMissing')
Apr 29 13:36:17 primary-xen-bms-jlr SM: [31402]
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] ]]
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] ]]
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] ]]
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] Logging in to [10.10.10.3:3260]
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:21 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['11']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] IQN match. Incrementing sessions to 1
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] LUNprint: waiting for path: /dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] adapter=11
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['/usr/bin/sg_luns', '-q', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['/usr/bin/sg_scan']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] Got all 2 sg devices
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['/sbin/udevadm', 'settle']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] Dom0 disks: ['/dev/sdb']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['ls', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', '-1', '--color=never']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['uuidgen', '-r']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['md5sum']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread3 SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['sginfo', '-M', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['sginfo', '-s', '/dev/sdc']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811]   pread SUCCESS
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:36:26 primary-xen-bms-jlr SM: [31811] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811] ]]
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811] Raising exception [107, The SCSIid parameter is missing or incorrect]
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The SCSIid parameter is missing or incorrect
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811]     self.load(sr_uuid)
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811]   File "/opt/xensource/sm/LVMoISCSISR", line 190, in load
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811]     raise xs_errors.XenError('ConfigSCSIid')
Apr 29 13:36:27 primary-xen-bms-jlr SM: [31811]
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] Raising exception [96, The request is missing or has an incorrect target IQN parameter]
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The request is missing or has an incorrect target IQN parameter
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]     self.load(sr_uuid)
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]   File "/opt/xensource/sm/LVMoISCSISR", line 86, in load
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]     iscsi = BaseISCSI.BaseISCSISR(self.original_srcmd, sr_uuid)
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]     self.load(sr_uuid)
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]   File "/opt/xensource/sm/BaseISCSI.py", line 212, in load
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]     raise xs_errors.XenError('ConfigTargetIQNMissing')
Apr 29 13:38:26 primary-xen-bms-jlr SM: [6036]
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] ]]
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] ]]
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] ]]
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] Logging in to [10.10.10.3:3260]
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:30 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['11']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] IQN match. Incrementing sessions to 1
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] LUNprint: waiting for path: /dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] adapter=11
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/usr/bin/sg_luns', '-q', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/usr/bin/sg_scan']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] Got all 3 sg devices
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/sbin/udevadm', 'settle']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] Dom0 disks: ['/dev/sdb']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['ls', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', '-1', '--color=never']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['uuidgen', '-r']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['md5sum']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread3 SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['sginfo', '-M', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['sginfo', '-s', '/dev/sdc']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['uuidgen', '-r']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN20']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['md5sum']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread3 SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['sginfo', '-M', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN20']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['sginfo', '-s', '/dev/sdd']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN20']
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099]   pread SUCCESS
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:35 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] ]]
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] Raising exception [107, The SCSIid parameter is missing or incorrect]
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The SCSIid parameter is missing or incorrect
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099]     self.load(sr_uuid)
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099]   File "/opt/xensource/sm/LVMoISCSISR", line 190, in load
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099]     raise xs_errors.XenError('ConfigSCSIid')
Apr 29 13:38:36 primary-xen-bms-jlr SM: [6099]
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] ['uuidgen', '-r']
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173]   pread SUCCESS
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: opening lock file /var/lock/sm/sr
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] LVMCache created for VG_XenStorage-93c99acd-b235-4b30-8589-15275f81d467
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] sr_probe {'subtask_of': 'DummyRef:|19ef24ae-539f-4267-ae7e-1ec441b8b806|SR.probe', 'sr_sm_config': {}, 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:aa4644ab-82c8-4898-abf3-5170a256e230', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_probe'}
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] ['uuidgen', '-r']
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173]   pread SUCCESS
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] ]]
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] ]]
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] Logging in to [10.10.10.3:3260]
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] Keep the current replacement_timout value: 144.
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:41 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173]   pread SUCCESS
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] ['11']
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] IQN match. Incrementing sessions to 1
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] Set scheduler to [noop] on [/sys/block/sdd]
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] lock: opening lock file /var/lock/sm/136caa96-295d-430a-9cc3-788cd62f1eea/sr
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] LVMCache created for VG_XenStorage-136caa96-295d-430a-9cc3-788cd62f1eea
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] ['/sbin/pvs', '--noheadings', '-o', 'vg_name', '/dev/disk/by-id/scsi-360000000000000000e00000000010014']
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Failed to find physical volume "/dev/sdd".
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] '
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:46 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:47 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:47 primary-xen-bms-jlr SM: [7173] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:47 primary-xen-bms-jlr SM: [7173] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:47 primary-xen-bms-jlr SM: [7173] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:47 primary-xen-bms-jlr SM: [7173] ]]
Apr 29 13:38:47 primary-xen-bms-jlr SM: [7173] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:47 primary-xen-bms-jlr SM: [7173] sr_probe result: <?xml version="1.0" ?><SRlist/>
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3" not found
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016]   Cannot process volume group VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] '
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] LVMCache: will initialize now
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] LVMCache: refreshing
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3" not found
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016]   Cannot process volume group VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] '
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] sr_create {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|65cddc06-d8c6-40ad-90f6-00fb744ef6ef|SR.create', 'args': ['0'], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:b9869406-5d91-4eb3-ae97-b6ec4e03c7bd', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_create', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] ]]
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] ]]
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] Logging in to [10.10.10.3:3260]
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] Keep the current replacement_timout value: 144.
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:52 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   pread SUCCESS
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['11']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] IQN match. Incrementing sessions to 1
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] Set scheduler to [noop] on [/sys/block/sdd]
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3" not found
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   Cannot process volume group VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] '
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] LVMCache: will initialize now
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] LVMCache: refreshing
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3" not found
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   Cannot process volume group VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] '
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] LVHDSR.create for fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3" not found
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   Cannot process volume group VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] '
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['sginfo', '-s', '/dev/sdd']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   pread SUCCESS
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/sdd']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   pread SUCCESS
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/bin/dd', 'if=/dev/zero', 'of=/dev/disk/by-id/scsi-360000000000000000e00000000010014', 'bs=1M', 'count=10', 'oflag=direct']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   pread SUCCESS
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/sbin/vgcreate', '--metadatasize', '10M', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3', '/dev/disk/by-id/scsi-360000000000000000e00000000010014']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   pread SUCCESS
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/sbin/vgchange', '-an', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   pread SUCCESS
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/disk/by-id/scsi-360000000000000000e00000000010014']
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016]   pread SUCCESS
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:57 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8016] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8016] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8016] ]]
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8016] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8016] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3" not found
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588]   Cannot process volume group VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] '
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] LVMCache: will initialize now
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] LVMCache: refreshing
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3" not found
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588]   Cannot process volume group VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] '
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] sr_attach {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|cab8ca65-20a4-446a-af40-48c969f6ac27|SR.attach', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:3fbfa2e7-bbca-4109-8a94-44f25d5bc068', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_attach', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:38:58 primary-xen-bms-jlr SMGC: [8588] === SR fc977153-d74c-d687-464e-df7cf3ac24c3: abort ===
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/gc_active
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: tried lock /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/gc_active, acquired: True (exists: True)
Apr 29 13:38:58 primary-xen-bms-jlr SMGC: [8588] abort: releasing the process lock
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/gc_active
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] RESET for SR fc977153-d74c-d687-464e-df7cf3ac24c3 (master: True)
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] set_dirty 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730' succeeded
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] ]]
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] Raising exception [202, General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] ]]
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] ['iscsiadm', '-m', 'session'] failed with (u'General backend error [opterr=rc: 21, stdout: , stderr: iscsiadm: No active sessions.\n]',)
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] Logging in to [10.10.10.3:3260]
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] Keep the current replacement_timout value: 144.
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:38:58 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['11']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] IQN match. Incrementing sessions to 1
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] Set scheduler to [noop] on [/sys/block/sdd]
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVMCache: will initialize now
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVMCache: refreshing
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVHDSR.attach for fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] Entering _checkMetadataVolume
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] Creating Metadata volume
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/lvcreate', '-n', 'MGT', '-L', '4', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3', '-W', 'n']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/lvchange', '-ay', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3/MGT']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] Calling devlist_to_serial
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/disk/by-id/scsi-360000000000000000e00000000010014']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] Dom0 disks: ['/dev/sdb']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] Block scheduler: /dev/disk/by-id/scsi-360000000000000000e00000000010014 (/dev/sdd) wants noop
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] Set scheduler to [noop] on [/sys/block/sdd]
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVMCache: will initialize now
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVMCache: refreshing
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] LVHDSR.scan for fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:03 primary-xen-bms-jlr SM: [8588] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] LVMCache: refreshing
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: tried lock /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running, acquired: True (exists: True)
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] Kicking GC
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8588] === SR fc977153-d74c-d687-464e-df7cf3ac24c3: gc ===
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8996] Will finish as PID [8997]
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8997] lock: closed /var/lock/sm/iscsiadm/running
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8997] lock: closed /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8588] New PID [8996]
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8588] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [8997] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997] *~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]          ***********************
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]          *  E X C E P T I O N  *
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]          ***********************
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997] gc: EXCEPTION <class 'util.SMException'>, SR fc977153-d74c-d687-464e-df7cf3ac24c3 not attached on this host
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]   File "/opt/xensource/sm/cleanup.py", line 3379, in gc
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]     _gc(None, srUuid, dryRun)
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]   File "/opt/xensource/sm/cleanup.py", line 3258, in _gc
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]     sr = SR.getInstance(srUuid, session)
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]   File "/opt/xensource/sm/cleanup.py", line 1554, in getInstance
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]     return LVHDSR(uuid, xapi, createLock, force)
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]   File "/opt/xensource/sm/cleanup.py", line 2561, in __init__
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]     SR.__init__(self, uuid, xapi, createLock, force)
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]   File "/opt/xensource/sm/cleanup.py", line 1582, in __init__
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]     raise util.SMException("SR %s not attached on this host" % uuid)
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997] *~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*~*
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997] * * * * * SR fc977153-d74c-d687-464e-df7cf3ac24c3: ERROR
Apr 29 13:39:04 primary-xen-bms-jlr SMGC: [8997]
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] LVMCache: will initialize now
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] LVMCache: refreshing
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] Entering _checkMetadataVolume
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] sr_update {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|e31ab9b7-aa86-4dae-91e0-d56fe7c016ec|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:8f099bf6-95f2-4d2a-9d99-73bf9e08f49a', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] Setting virtual_allocation of SR fc977153-d74c-d687-464e-df7cf3ac24c3 to 0
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] Updating metadata : {'objtype': 'sr', 'name_description': 'iSCSI SR [10.10.10.3 (iqn.2015.com.halizard:noSAN; LUN 20: beaf120: 931 GB (IET))]', 'name_label': 'iSCSI virtual disk storage'}
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] entering updateSR
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9022] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] LVMCache: will initialize now
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] LVMCache: refreshing
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] Entering _checkMetadataVolume
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] sr_update {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|b51e6270-b612-424d-b67c-2ebb7c0cc7d7|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:08b7b929-2f99-4c50-850c-9728f9dc21a7', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] Setting virtual_allocation of SR fc977153-d74c-d687-464e-df7cf3ac24c3 to 0
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054]   pread SUCCESS
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] Updating metadata : {'objtype': 'sr', 'name_description': 'iSCSI SR [10.10.10.3 (iqn.2015.com.halizard:noSAN; LUN 20: beaf120: 931 GB (IET))]', 'name_label': 'iSCSI virtual disk storage'}
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] entering updateSR
Apr 29 13:39:04 primary-xen-bms-jlr SM: [9054] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVMCache: will initialize now
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVMCache: refreshing
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] Entering _checkMetadataVolume
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] sr_scan {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|ab08069d-c698-4e13-a844-b26a72184536|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:4872bc63-bf9d-493f-b559-48687f99df31', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVMCache: will initialize now
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVMCache: refreshing
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] Entering _checkMetadataVolume
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVHDSR.scan for fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] LVMCache: refreshing
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: tried lock /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running, acquired: True (exists: True)
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] Kicking GC
Apr 29 13:39:06 primary-xen-bms-jlr SMGC: [9479] === SR fc977153-d74c-d687-464e-df7cf3ac24c3: gc ===
Apr 29 13:39:06 primary-xen-bms-jlr SMGC: [9519] Will finish as PID [9520]
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: closed /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/running
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/gc_active
Apr 29 13:39:06 primary-xen-bms-jlr SMGC: [9479] New PID [9519]
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9479] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: tried lock /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/gc_active, acquired: True (exists: True)
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: tried lock /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr, acquired: True (exists: True)
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] LVMCache: refreshing
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SMGC: [9520] SR fc97 ('iSCSI virtual disk storage') (0 VDIs in 0 VHD trees): no changes
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SMGC: [9520] No work, exiting
Apr 29 13:39:06 primary-xen-bms-jlr SMGC: [9520] GC process exiting, no work left
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9520] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/gc_active
Apr 29 13:39:06 primary-xen-bms-jlr SMGC: [9520] SR fc97 ('iSCSI virtual disk storage') (0 VDIs in 0 VHD trees): no changes
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] LVMCache: will initialize now
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] LVMCache: refreshing
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] Entering _checkMetadataVolume
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] sr_update {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|a662a11d-4aff-409d-ae61-9a65994c2131|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:a01f00f4-0f8d-4bc7-a864-de99389537be', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] Setting virtual_allocation of SR fc977153-d74c-d687-464e-df7cf3ac24c3 to 0
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547]   pread SUCCESS
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] Updating metadata : {'objtype': 'sr', 'name_description': 'iSCSI SR [10.10.10.3 (iqn.2015.com.halizard:noSAN; LUN 20: beaf120: 931 GB (IET))]', 'name_label': 'iSCSI virtual disk storage'}
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] entering updateSR
Apr 29 13:39:06 primary-xen-bms-jlr SM: [9547] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] LVMCache: will initialize now
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] LVMCache: refreshing
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] Entering _checkMetadataVolume
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] sr_update {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|07cd48cd-4276-4a8f-960d-196e2b3adc8c|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:e27fbfef-3a91-4182-8811-0c2530216f4a', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] Setting virtual_allocation of SR fc977153-d74c-d687-464e-df7cf3ac24c3 to 0
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] Updating metadata : {'objtype': 'sr', 'name_description': 'iSCSI SR [10.10.10.3 (iqn.2015.com.halizard:noSAN; LUN 20: beaf120: 931 GB (IET))]', 'name_label': 'iSCSI virtual disk storage'}
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] entering updateSR
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9586] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e00000000010014
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: opening lock file /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] LVMCache created for VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] LVMCache: will initialize now
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] LVMCache: refreshing
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] Entering _checkMetadataVolume
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: acquired /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] sr_update {'sr_uuid': 'fc977153-d74c-d687-464e-df7cf3ac24c3', 'subtask_of': 'DummyRef:|c6257cf0-2733-4a36-8797-ecf6ed370a67|SR.stat', 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:b7c22aa6-90ab-482d-bee7-b86be1f3c6ed', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e00000000010014', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_update', 'sr_ref': 'OpaqueRef:317558d1-d62f-46fe-a8c0-a181ba28d730'}
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] ['/sbin/vgs', '--readonly', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] Setting virtual_allocation of SR fc977153-d74c-d687-464e-df7cf3ac24c3 to 0
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] ['/sbin/vgs', '--noheadings', '--nosuffix', '--units', 'b', 'VG_XenStorage-fc977153-d74c-d687-464e-df7cf3ac24c3']
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620]   pread SUCCESS
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] Updating metadata : {'objtype': 'sr', 'name_description': 'iSCSI SR [10.10.10.3 (iqn.2015.com.halizard:noSAN; LUN 20: beaf120: 931 GB (IET))]', 'name_label': 'iSCSI virtual disk storage'}
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] entering updateSR
Apr 29 13:39:07 primary-xen-bms-jlr SM: [9620] lock: released /var/lock/sm/fc977153-d74c-d687-464e-df7cf3ac24c3/sr
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] _testHost: Testing host/port: 10.10.10.3,3260
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] Raising exception [96, The request is missing or has an incorrect target IQN parameter]
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The request is missing or has an incorrect target IQN parameter
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]     self.load(sr_uuid)
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]   File "/opt/xensource/sm/LVMoISCSISR", line 86, in load
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]     iscsi = BaseISCSI.BaseISCSISR(self.original_srcmd, sr_uuid)
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]     self.load(sr_uuid)
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]   File "/opt/xensource/sm/BaseISCSI.py", line 212, in load
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]     raise xs_errors.XenError('ConfigTargetIQNMissing')
Apr 29 13:39:30 primary-xen-bms-jlr SM: [10695]
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] ['11']
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] Rescanning host adapter 11
Apr 29 13:39:34 primary-xen-bms-jlr SM: [10757] Full rescan of HostID 11
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] Refreshing channel 11:0:0
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] Dom0 disks: ['/dev/sdb']
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] ['/usr/bin/sg_luns', '-q', '/dev/disk/by-scsibus/360000000000000000e00000000010014-11:0:0:20']
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] sg_luns query returned [0, 10, 20]
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/disk/by-scsibus/360000000000000000e00000000010014-11:0:0:20']
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/disk/by-scsibus/360000000000000000e0000000001000a-11:0:0:10']
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] Probing new HBTL: ['', '11', '0', '0', '0']
Apr 29 13:39:36 primary-xen-bms-jlr SM: [10757] scsi add-single-device 11 0 0 0
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['11']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] IQN match. Incrementing sessions to 1
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] LUNprint: waiting for path: /dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] adapter=11
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/usr/bin/sg_luns', '-q', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/usr/bin/sg_scan']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] Got all 3 sg devices
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/sbin/udevadm', 'settle']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] Dom0 disks: ['/dev/sdb']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['ls', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', '-1', '--color=never']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['uuidgen', '-r']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['md5sum']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread3 SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['sginfo', '-M', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['sginfo', '-s', '/dev/sdc']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN10']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['uuidgen', '-r']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN20']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['md5sum']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread3 SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['sginfo', '-M', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN20']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['sginfo', '-s', '/dev/sdd']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260/LUN20']
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   pread SUCCESS
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] Raising exception [107, The SCSIid parameter is missing or incorrect]
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The SCSIid parameter is missing or incorrect
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   File "/opt/xensource/sm/SRCommand.py", line 376, in run
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]     sr = driver(cmd, cmd.sr_uuid)
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   File "/opt/xensource/sm/SR.py", line 156, in __init__
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]     self.load(sr_uuid)
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]   File "/opt/xensource/sm/LVMoISCSISR", line 190, in load
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]     raise xs_errors.XenError('ConfigSCSIid')
Apr 29 13:39:41 primary-xen-bms-jlr SM: [10757]
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] ['uuidgen', '-r']
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658]   pread SUCCESS
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: opening lock file /var/lock/sm/sr
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] LVMCache created for VG_XenStorage-e360cccc-487e-4c90-8b10-3f2d48be8e49
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] sr_probe {'subtask_of': 'DummyRef:|e22fecb7-6daa-4890-a0e5-2f93c8c13aab|SR.probe', 'sr_sm_config': {}, 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:ecbf5422-0b2b-4db3-a1bb-e9f6676e6bb1', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e0000000001000a', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_probe'}
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] ['uuidgen', '-r']
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658]   pread SUCCESS
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658]   pread SUCCESS
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] ['11']
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] IQN match. Incrementing sessions to 1
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] Set scheduler to [noop] on [/sys/block/sdc]
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: opening lock file /var/lock/sm/d2fd5b7e-bc0a-4049-b67a-39c7d5925bd3/sr
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] LVMCache created for VG_XenStorage-d2fd5b7e-bc0a-4049-b67a-39c7d5925bd3
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] ['/sbin/pvs', '--noheadings', '-o', 'vg_name', '/dev/disk/by-id/scsi-360000000000000000e0000000001000a']
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Failed to find physical volume "/dev/sdc".
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] '
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:46 primary-xen-bms-jlr SM: [11658] sr_probe result: <?xml version="1.0" ?><SRlist/>
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: opening lock file /var/lock/sm/23185cf3-9110-f1cc-5aba-1f7200670c3a/sr
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] LVMCache created for VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] ['/sbin/vgs', '--readonly', 'VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a']
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a" not found
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035]   Cannot process volume group VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] '
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] LVMCache: will initialize now
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] LVMCache: refreshing
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a']
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a" not found
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035]   Cannot process volume group VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] '
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: acquired /var/lock/sm/23185cf3-9110-f1cc-5aba-1f7200670c3a/sr
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] sr_create {'sr_uuid': '23185cf3-9110-f1cc-5aba-1f7200670c3a', 'subtask_of': 'DummyRef:|c2ff6b7b-c11f-473f-a301-6ca9b9d9eadd|SR.create', 'args': ['0'], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:952e0d07-0558-4fa7-9cb8-a41d4968ed0e', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e0000000001000a', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_create', 'sr_ref': 'OpaqueRef:ec28d001-bb17-4eb6-870d-4949526cd3c5'}
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035]   pread SUCCESS
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] ['11']
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] IQN match. Incrementing sessions to 1
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] Set scheduler to [noop] on [/sys/block/sdc]
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] LVMCache created for VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:50 primary-xen-bms-jlr SM: [12035] ['/sbin/vgs', '--readonly', 'VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a']
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a" not found
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   Cannot process volume group VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] '
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] LVMCache: will initialize now
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] LVMCache: refreshing
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a']
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a" not found
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   Cannot process volume group VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] '
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] LVHDSR.create for 23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ['/sbin/vgs', '--readonly', 'VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a']
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a" not found
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   Cannot process volume group VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] '
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ['sginfo', '-s', '/dev/sdc']
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   pread SUCCESS
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/sdc']
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   pread SUCCESS
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ['/bin/dd', 'if=/dev/zero', 'of=/dev/disk/by-id/scsi-360000000000000000e0000000001000a', 'bs=1M', 'count=10', 'oflag=direct']
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   pread SUCCESS
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ['/sbin/vgcreate', '--metadatasize', '10M', 'VG_XenStorage-23185cf3-9110-f1cc-5aba-1f7200670c3a', '/dev/disk/by-id/scsi-360000000000000000e0000000001000a']
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] FAILED in util.pread: (rc 5) stdout: '', stderr: 'WARNING: drbd signature detected on /dev/disk/by-id/scsi-360000000000000000e0000000001000a at offset 4000664891452. Wipe it? [y/n]: [n]
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   Aborted wiping of drbd.
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   1 existing signature left on the device.
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] '
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] Raising exception [77, Logical Volume group creation failed]
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] Raising exception [47, The SR is not available [opterr=Logical Volume group creation failed]]
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] lock: released /var/lock/sm/23185cf3-9110-f1cc-5aba-1f7200670c3a/sr
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ***** generic exception: sr_create: EXCEPTION <class 'SR.SROSError'>, The SR is not available [opterr=Logical Volume group creation failed]
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/SRCommand.py", line 110, in run
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     return self._run_locked(sr)
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/SRCommand.py", line 159, in _run_locked
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     rv = self._run(sr, target)
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/SRCommand.py", line 323, in _run
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     return sr.create(self.params['sr_uuid'], long(self.params['args'][0]))
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/LVMoISCSISR", line 471, in create
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     raise xs_errors.XenError("SRUnavailable", opterr=inst)
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The SR is not available [opterr=Logical Volume group creation failed]
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/SRCommand.py", line 378, in run
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     ret = cmd.run(sr)
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/SRCommand.py", line 110, in run
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     return self._run_locked(sr)
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/SRCommand.py", line 159, in _run_locked
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     rv = self._run(sr, target)
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/SRCommand.py", line 323, in _run
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     return sr.create(self.params['sr_uuid'], long(self.params['args'][0]))
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]   File "/opt/xensource/sm/LVMoISCSISR", line 471, in create
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]     raise xs_errors.XenError("SRUnavailable", opterr=inst)
Apr 29 13:39:51 primary-xen-bms-jlr SM: [12035]
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] ['uuidgen', '-r']
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256]   pread SUCCESS
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: opening lock file /var/lock/sm/sr
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] LVMCache created for VG_XenStorage-6ed5b8fe-d1df-4e1e-9cc3-1e5cc909a140
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] sr_probe {'subtask_of': 'DummyRef:|b79debd5-406d-4567-8277-e13ce87a4c70|SR.probe', 'sr_sm_config': {}, 'args': [], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:f1810be5-8e1a-4bf5-894f-6aa758f1dd74', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e0000000001000a', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_probe'}
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] ['uuidgen', '-r']
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256]   pread SUCCESS
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256]   pread SUCCESS
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] ['11']
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] IQN match. Incrementing sessions to 1
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] Set scheduler to [noop] on [/sys/block/sdc]
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: opening lock file /var/lock/sm/2a73de00-393e-4540-bcc0-095719b880b8/sr
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] LVMCache created for VG_XenStorage-2a73de00-393e-4540-bcc0-095719b880b8
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] ['/sbin/pvs', '--noheadings', '-o', 'vg_name', '/dev/disk/by-id/scsi-360000000000000000e0000000001000a']
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Failed to find physical volume "/dev/sdc".
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] '
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:49:31 primary-xen-bms-jlr SM: [8256] sr_probe result: <?xml version="1.0" ?><SRlist/>
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] Setting LVM_DEVICE to /dev/disk/by-scsid/360000000000000000e0000000001000a
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: opening lock file /var/lock/sm/795534cb-07e2-10bf-c2ed-0ae21e94b5e1/sr
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] LVMCache created for VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: opening lock file /var/lock/sm/.nil/lvm
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/sbin/vgs', '--readonly', 'VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1" not found
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   Cannot process volume group VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] '
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] LVMCache: will initialize now
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] LVMCache: refreshing
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1" not found
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   Cannot process volume group VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] '
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: acquired /var/lock/sm/795534cb-07e2-10bf-c2ed-0ae21e94b5e1/sr
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] sr_create {'sr_uuid': '795534cb-07e2-10bf-c2ed-0ae21e94b5e1', 'subtask_of': 'DummyRef:|3bf0d705-ced1-46ac-9791-50a47de8822d|SR.create', 'args': ['0'], 'host_ref': 'OpaqueRef:9f5cf0b8-af7c-47d0-a650-984b5c0176bb', 'session_ref': 'OpaqueRef:9f734e1a-dd81-4ac3-84c4-097cf1d0ee9e', 'device_config': {'target': '10.10.10.3', 'SCSIid': '360000000000000000e0000000001000a', 'targetIQN': 'iqn.2015.com.halizard:noSAN', 'SRmaster': 'true', 'port': '3260'}, 'command': 'sr_create', 'sr_ref': 'OpaqueRef:fc96f693-279c-47c9-9454-09f4e171029f'}
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: opening lock file /var/lock/sm/iscsiadm/running
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: acquired /var/lock/sm/iscsiadm/running
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: released /var/lock/sm/iscsiadm/running
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['ls', '/sys/class/scsi_host', '-1', '--color=never']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   pread SUCCESS
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['11']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] PATHDICT: key 10.10.10.3:3260: {'path': '/dev/iscsi/iqn.2015.com.halizard:noSAN/10.10.10.3:3260', 'ipaddr': '10.10.10.3', 'port': 3260L}
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] IQN match. Incrementing sessions to 1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] Set scheduler to [noop] on [/sys/block/sdc]
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] LVMCache created for VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/sbin/vgs', '--readonly', 'VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1" not found
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   Cannot process volume group VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] '
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] LVMCache: will initialize now
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] LVMCache: refreshing
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/sbin/lvs', '--noheadings', '--units', 'b', '-o', '+lv_tags', '/dev/VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1" not found
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   Cannot process volume group VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] '
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] LVHDSR.create for 795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/sbin/vgs', '--readonly', 'VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] FAILED in util.pread: (rc 5) stdout: '', stderr: '  Volume group "VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1" not found
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   Cannot process volume group VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] '
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['sginfo', '-s', '/dev/sdc']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   pread SUCCESS
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/usr/lib/udev/scsi_id', '-g', '--device', '/dev/sdc']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   pread SUCCESS
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/bin/dd', 'if=/dev/zero', 'of=/dev/disk/by-id/scsi-360000000000000000e0000000001000a', 'bs=1M', 'count=10', 'oflag=direct']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   pread SUCCESS
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: acquired /var/lock/sm/.nil/lvm
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ['/sbin/vgcreate', '--metadatasize', '10M', 'VG_XenStorage-795534cb-07e2-10bf-c2ed-0ae21e94b5e1', '/dev/disk/by-id/scsi-360000000000000000e0000000001000a']
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] FAILED in util.pread: (rc 5) stdout: '', stderr: 'WARNING: drbd signature detected on /dev/disk/by-id/scsi-360000000000000000e0000000001000a at offset 4000664891452. Wipe it? [y/n]: [n]
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   Aborted wiping of drbd.
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   1 existing signature left on the device.
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] '
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: released /var/lock/sm/.nil/lvm
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] Raising exception [77, Logical Volume group creation failed]
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] Raising exception [47, The SR is not available [opterr=Logical Volume group creation failed]]
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] lock: released /var/lock/sm/795534cb-07e2-10bf-c2ed-0ae21e94b5e1/sr
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ***** generic exception: sr_create: EXCEPTION <class 'SR.SROSError'>, The SR is not available [opterr=Logical Volume group creation failed]
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/SRCommand.py", line 110, in run
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     return self._run_locked(sr)
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/SRCommand.py", line 159, in _run_locked
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     rv = self._run(sr, target)
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/SRCommand.py", line 323, in _run
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     return sr.create(self.params['sr_uuid'], long(self.params['args'][0]))
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/LVMoISCSISR", line 471, in create
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     raise xs_errors.XenError("SRUnavailable", opterr=inst)
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879] ***** LVHD over iSCSI: EXCEPTION <class 'SR.SROSError'>, The SR is not available [opterr=Logical Volume group creation failed]
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/SRCommand.py", line 378, in run
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     ret = cmd.run(sr)
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/SRCommand.py", line 110, in run
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     return self._run_locked(sr)
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/SRCommand.py", line 159, in _run_locked
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     rv = self._run(sr, target)
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/SRCommand.py", line 323, in _run
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     return sr.create(self.params['sr_uuid'], long(self.params['args'][0]))
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]   File "/opt/xensource/sm/LVMoISCSISR", line 471, in create
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]     raise xs_errors.XenError("SRUnavailable", opterr=inst)
Apr 29 13:49:40 primary-xen-bms-jlr SM: [8879]