Hi all,
I'm about to lose one very important vm. I shut down this vm for
maintenance and then I moved the four disks to a new created lun. This
vm has 2 snapshots.
After successful move, the vm refuses to start with this message:
Bad volume specification {u'index': 0, u'domainID':
u'961ea94a-aced-4dd0-a9f0-266ce1810177', 'reqsize': '0', u'format':
u'cow', u'bootOrder': u'1', u'discard': False, u'volumeID':
u'a0b6d5cb-db1e-4c25-aaaf-1bbee142c60b', 'apparentsize': '2147483648',
u'imageID': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', u'specParams': {},
u'readonly': u'false', u'iface': u'virtio', u'optional': u'false',
u'deviceId': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', 'truesize':
'2147483648', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350',
u'device': u'disk', u'shared': u'false', u'propagateErrors': u'off',
u'type': u'disk'}.
I tried to merge the snaphots, export , clone from snapshot, copy disks,
or deactivate disks and every action fails when it is about disk.
I began to dd lv group to get a new vm intended to a standalone
libvirt/kvm, the vm quite boots up but it is an outdated version before
the first snapshot. There is a lot of disks when doing a "lvs | grep
961ea94a" supposed to be disks snapshots. Which of them must I choose to
get the last vm before shutting down? I'm not used to deal snapshot with
virsh/libvirt, so some help will be much appreciated.
Is there some unknown command to recover this vm into ovirt?
Thank you in advance.
--
Nathanaël Blanchet
Supervision réseau
Pôle Infrastrutures Informatiques
227 avenue Professeur-Jean-Louis-Viala
34193 MONTPELLIER CEDEX 5
Tél. 33 (0)4 67 54 84 55
Fax 33 (0)4 67 54 84 14
blanc...@abes.fr
Event ID: -1, Message: VM hortensia was started by sblanc...@levant.abes.fr@abes.fr-authz (Host: aquilon).
2017-12-06 11:01:16,292+01 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-10) [] VM 'f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec' was reported as Down on VDS 'b692c250-4f71-4569-801f-6bfd3b8f50b9'(aquilon)
2017-12-06 11:01:16,294+01 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-10) [] START, DestroyVDSCommand(HostName = aquilon, DestroyVmVDSCommandParameters:{runAsync='true', hostId='b692c250-4f71-4569-801f-6bfd3b8f50b9', vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec', force='false', secondsToWait='0', gracefully='false', reason='', ignoreNoVm='true'}), log id: 6ddce93f
2017-12-06 11:01:17,301+01 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-10) [] FINISH, DestroyVDSCommand, log id: 6ddce93f
2017-12-06 11:01:17,301+01 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-10) [] VM 'f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'(hortensia) moved from 'WaitForLaunch' --> 'Down'
2017-12-06 11:01:17,399+01 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ForkJoinPool-1-worker-10) [] EVENT_ID: VM_DOWN_ERROR(119), Correlation ID: null, Call Stack: null, Custom ID: null, Custom Event ID: -1, Message: VM hortensia is down with error. Exit message: Bad volume specification {u'index': 0, u'domainID': u'961ea94a-aced-4dd0-a9f0-266ce1810177', 'reqsize': '0', u'format': u'cow', u'bootOrder': u'1', u'discard': False, u'volumeID': u'a0b6d5cb-db1e-4c25-aaaf-1bbee142c60b', 'apparentsize': '2147483648', u'imageID': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', u'specParams': {}, u'readonly': u'false', u'iface': u'virtio', u'optional': u'false', u'deviceId': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', 'truesize': '2147483648', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350', u'device': u'disk', u'shared': u'false', u'propagateErrors': u'off', u'type': u'disk'}.
2017-12-06 11:01:17,400+01 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-10) [] add VM 'f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'(hortensia) to rerun treatment
2017-12-06 11:01:17,404+01 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring] (ForkJoinPool-1-worker-10) [] Rerun VM 'f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'. Called from VDS 'aquilon'
2017-12-06 11:01:17,466+01 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-7-thread-39) [] EVENT_ID: USER_INITIATED_RUN_VM_FAILED(151), Correlation ID: d6fc6f3b-b3b2-466d-8fcd-c145d3cf645a, Job ID: 5674a186-14c2-46f3-9008-99fd9d3fd979, Call Stack: null, Custom ID: null, Custom Event ID: -1, Message: Failed to run VM hortensia on Host aquilon.
2017-12-06 11:01:17,474+01 INFO [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-7-thread-39) [] Lock Acquired to object 'EngineLock:{exclusiveLocks='[f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec=VM]', sharedLocks=''}'
2017-12-06 11:01:17,525+01 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-7-thread-39) [] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{runAsync='true', vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'}), log id: 4e16e24
2017-12-06 11:01:17,525+01 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-7-thread-39) [] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 4e16e24
2017-12-06 11:01:17,545+01 WARN [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-7-thread-39) [] Validation of action 'RunVm' failed for user sblanc...@levant.abes.fr@abes.fr-authz. Reasons: VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_NO_HOSTS
2017-12-06 11:01:17,545+01 INFO [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-7-thread-39) [] Lock freed to object 'EngineLock:{exclusiveLocks='[f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec=VM]', sharedLocks=''}'
2017-12-06 11:01:17,554+01 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-7-thread-39) [] EVENT_ID: USER_FAILED_RUN_VM(54), Correlation ID: d6fc6f3b-b3b2-466d-8fcd-c145d3cf645a, Job ID: 5674a186-14c2-46f3-9008-99fd9d3fd979, Call Stack: null, Custom ID: null, Custom Event ID: -1, Message: Failed to run VM hortensia (User: sblanc...@levant.abes.fr@abes.fr-authz).
[root@aura ~]# tail -f /var/log/vdsm/vdsm.log
2017-12-06 11:00:49,987+0100 INFO (event/40) [vds] vmContainerLock acquired (clientIF:187)
2017-12-06 11:00:50,127+0100 INFO (event/41) [storage.StoragePool] Linking /rhev/data-center/mnt/blockSD/a2decc25-bb4b-4b63-880a-09145367305c to /rhev/data-center/48ca3019-9dbf-4ef3-98e9-08105d396350/a2decc25-bb4b-4b63-880a-09145367305c (sp:1220)
2017-12-06 11:00:50,211+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.hostdevListByCaps succeeded in 1.76 seconds (__init__:539)
2017-12-06 11:00:59,333+0100 INFO (jsonrpc/6) [vdsm.api] START repoStats(options=None) from=::ffff:10.34.100.46,56936, flow_id=554e84ea, task_id=fd05ecfe-888f-4ed8-842f-7f41a4a41cf4 (api:46)
2017-12-06 11:00:59,334+0100 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={u'0c9eb935-988d-4523-ae8d-bf3b34adddbb': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000263221', 'lastCheck': '1.6', 'valid': True}, u'f7d7a540-6f5a-420e-9fc3-94badbf6091f': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000324101', 'lastCheck': '0.7', 'valid': True}, u'961ea94a-aced-4dd0-a9f0-266ce1810177': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000563141', 'lastCheck': '0.8', 'valid': True}, u'af2589e5-fed5-465e-b528-b7cbca39c04f': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000460511', 'lastCheck': '1.4', 'valid': True}, u'a2decc25-bb4b-4b63-880a-09145367305c': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000553604', 'lastCheck': '0.4', 'valid': True}, u'58a6dca8-181e-4b66-944b-c9b4007370d3': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000340491', 'lastCheck': '0.7', 'valid': True}, u'5ef8572c-0ab5-4491-994a-e4c30230a525': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000358234', 'lastCheck': '1.6', 'valid': True}} from=::ffff:10.34.100.46,56936, flow_id=554e84ea, task_id=fd05ecfe-888f-4ed8-842f-7f41a4a41cf4 (api:52)
2017-12-06 11:00:59,351+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.02 seconds (__init__:539)
2017-12-06 11:00:59,739+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-12-06 11:01:01,646+0100 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=57c026cb-323c-4731-870e-a345334db71b (api:46)
2017-12-06 11:01:01,647+0100 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'0c9eb935-988d-4523-ae8d-bf3b34adddbb': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000263221', 'lastCheck': '3.9', 'valid': True}, u'f7d7a540-6f5a-420e-9fc3-94badbf6091f': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000324101', 'lastCheck': '3.0', 'valid': True}, u'961ea94a-aced-4dd0-a9f0-266ce1810177': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000563141', 'lastCheck': '3.1', 'valid': True}, u'af2589e5-fed5-465e-b528-b7cbca39c04f': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000460511', 'lastCheck': '3.8', 'valid': True}, u'a2decc25-bb4b-4b63-880a-09145367305c': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000553604', 'lastCheck': '2.7', 'valid': True}, u'58a6dca8-181e-4b66-944b-c9b4007370d3': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000340491', 'lastCheck': '3.0', 'valid': True}, u'5ef8572c-0ab5-4491-994a-e4c30230a525': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000358234', 'lastCheck': '4.0', 'valid': True}} from=internal, task_id=57c026cb-323c-4731-870e-a345334db71b (api:52)
2017-12-06 11:01:01,815+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-12-06 11:01:14,236+0100 INFO (jsonrpc/0) [vdsm.api] START __init__(cif=<clientIF.clientIF object at 0x34e7150>, params={u'acpiEnable': u'true', u'emulatedMachine': u'pc-i440fx-rhel7.3.0', u'vmId': u'f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec', u'memGuaranteedSize': 6144, u'transparentHugePages': u'true', u'timeOffset': u'0', u'cpuType': u'SandyBridge', u'serial': u'f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec', u'smp': u'8', u'guestNumaNodes': [{u'nodeIndex': 0, u'cpus': u'0,1,2,3,4,5,6,7', u'memory': u'6144'}], u'custom': {u'device_e639e23d-6422-48a3-92f0-158e6f38f37c': u"VmDevice:{id='VmDeviceId:{deviceId='e639e23d-6422-48a3-92f0-158e6f38f37c', vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'}', device='ide', type='CONTROLLER', bootOrder='0', specParams='[]', address='{slot=0x01, bus=0x00, domain=0x0000, type=pci, function=0x1}', managed='false', plugged='true', readOnly='false', deviceAlias='ide', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}", u'device_e639e23d-6422-48a3-92f0-158e6f38f37cdevice_e0ced3d8-abb3-4ecc-948d-a52a2c0a770edevice_47f0af28-4812-484a-a311-f3384268f11e': u"VmDevice:{id='VmDeviceId:{deviceId='47f0af28-4812-484a-a311-f3384268f11e', vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'}', device='spicevmc', type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0, controller=0, type=virtio-serial, port=3}', managed='false', plugged='true', readOnly='false', deviceAlias='channel2', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}", u'device_e639e23d-6422-48a3-92f0-158e6f38f37cdevice_e0ced3d8-abb3-4ecc-948d-a52a2c0a770edevice_47f0af28-4812-484a-a311-f3384268f11edevice_500450db-95e5-43c1-b901-1cfa60f11b40': u"VmDevice:{id='VmDeviceId:{deviceId='500450db-95e5-43c1-b901-1cfa60f11b40', vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'}', device='unix', type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0, controller=0, type=virtio-serial, port=2}', managed='false', plugged='true', readOnly='false', deviceAlias='channel1', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}", u'device_e639e23d-6422-48a3-92f0-158e6f38f37cdevice_e0ced3d8-abb3-4ecc-948d-a52a2c0a770e': u"VmDevice:{id='VmDeviceId:{deviceId='e0ced3d8-abb3-4ecc-948d-a52a2c0a770e', vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec'}', device='unix', type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0, controller=0, type=virtio-serial, port=1}', managed='false', plugged='true', readOnly='false', deviceAlias='channel0', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}"}, u'vmType': u'kvm', u'memSize': 6144, u'smpCoresPerSocket': u'1', u'vmName': u'hortensia', u'nice': u'0', u'bootMenuEnable': u'false', u'smpThreadsPerCore': u'1', u'smartcardEnable': u'false', u'maxMemSlots': 16, u'kvmEnable': u'true', u'pitReinjection': u'false', u'displayNetwork': u'br100', u'devices': [{u'device': u'qxl', u'specParams': {u'vram': u'32768', u'vgamem': u'16384', u'heads': u'1', u'ram': u'65536'}, u'type': u'video', u'deviceId': u'7d57caa6-3385-4c7b-ba03-9fe47dfce307', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x02'}}, {u'device': u'vnc', u'specParams': {u'fileTransferEnable': u'false', u'spiceSecureChannels': u'smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir', u'keyMap': u'fr', u'copyPasteEnable': u'true'}, u'type': u'graphics', u'deviceId': u'44607e2a-80a6-443a-96b5-bece5b510bbb'}, {u'device': u'spice', u'specParams': {u'fileTransferEnable': u'false', u'spiceSecureChannels': u'smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir', u'keyMap': u'fr', u'copyPasteEnable': u'true'}, u'type': u'graphics', u'deviceId': u'00000098-0098-0098-0098-000000000153'}, {u'index': u'2', u'iface': u'ide', u'specParams': {u'path': u''}, u'readonly': u'true', u'deviceId': u'bd3b4d5b-4365-4957-a0ec-438cac0a39e7', u'address': {u'bus': u'1', u'controller': u'0', u'type': u'drive', u'target': u'0', u'unit': u'0'}, u'device': u'cdrom', u'shared': u'false', u'path': u'', u'type': u'disk'}, {u'index': 0, u'domainID': u'961ea94a-aced-4dd0-a9f0-266ce1810177', u'bootOrder': u'1', u'format': u'cow', u'type': u'disk', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350', u'volumeID': u'a0b6d5cb-db1e-4c25-aaaf-1bbee142c60b', u'imageID': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', u'specParams': {}, u'readonly': u'false', u'iface': u'virtio', u'deviceId': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', u'shared': u'false', u'device': u'disk', u'discard': False, u'propagateErrors': u'off', u'optional': u'false'}, {u'domainID': u'961ea94a-aced-4dd0-a9f0-266ce1810177', u'format': u'cow', u'type': u'disk', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350', u'volumeID': u'8699ea2d-3c23-47c6-b4be-eb377809bd15', u'imageID': u'79d2fdfe-5545-4ba3-a133-4aec4684f0d7', u'specParams': {}, u'readonly': u'false', u'iface': u'virtio', u'deviceId': u'79d2fdfe-5545-4ba3-a133-4aec4684f0d7', u'shared': u'false', u'device': u'disk', u'discard': False, u'propagateErrors': u'off', u'optional': u'false'}, {u'domainID': u'961ea94a-aced-4dd0-a9f0-266ce1810177', u'format': u'cow', u'type': u'disk', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350', u'volumeID': u'aeeb8808-b0af-4c0f-918e-81402e5494de', u'imageID': u'9b65853c-fc02-47d1-b310-93f37c7f5bfe', u'specParams': {}, u'readonly': u'false', u'iface': u'virtio', u'deviceId': u'9b65853c-fc02-47d1-b310-93f37c7f5bfe', u'shared': u'false', u'device': u'disk', u'discard': False, u'propagateErrors': u'off', u'optional': u'false'}, {u'domainID': u'961ea94a-aced-4dd0-a9f0-266ce1810177', u'format': u'cow', u'type': u'disk', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350', u'volumeID': u'6d07d624-b8fe-47d3-915a-1193fbfaa875', u'imageID': u'c98a3029-a06b-460f-bc11-550ac4a879ce', u'specParams': {}, u'readonly': u'false', u'iface': u'virtio', u'deviceId': u'c98a3029-a06b-460f-bc11-550ac4a879ce', u'shared': u'false', u'device': u'disk', u'discard': False, u'propagateErrors': u'off', u'optional': u'false'}, {u'nicModel': u'pv', u'macAddr': u'00:1a:4a:22:1e:0b', u'linkActive': u'true', u'network': u'brv22', u'filter': u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'f7dbc891-4736-4d5a-98be-1446e1bd80d6', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x03'}, u'device': u'bridge', u'type': u'interface'}, {u'nicModel': u'pv', u'macAddr': u'00:1a:4a:22:1e:0c', u'linkActive': u'true', u'network': u'brv11', u'filter': u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'42eb2e91-e259-403d-90ac-8a89a60b61e1', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x04'}, u'device': u'bridge', u'type': u'interface'}, {u'nicModel': u'pv', u'macAddr': u'00:1a:4a:22:1e:0d', u'linkActive': u'true', u'network': u'brv16', u'filter': u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'240f875c-169e-4bc9-92bf-bd3b5a4f914b', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x05'}, u'device': u'bridge', u'type': u'interface'}, {u'nicModel': u'pv', u'macAddr': u'00:1a:4a:22:1e:c3', u'linkActive': u'true', u'network': u'brv104', u'filter': u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'38dd20a8-c72c-4c58-8926-642452efae20', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x0d'}, u'device': u'bridge', u'type': u'interface'}, {u'nicModel': u'pv', u'macAddr': u'00:1a:4a:22:1f:3b', u'linkActive': u'true', u'network': u'brv106', u'filter': u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'c7f3a6c2-afa0-4d72-a486-eecd5d6b1106', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x0c'}, u'device': u'bridge', u'type': u'interface'}, {u'nicModel': u'pv', u'macAddr': u'00:1a:4a:22:1f:ef', u'linkActive': u'true', u'network': u'brv3', u'filter': u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'f6da093c-0371-46cf-9f52-60ffbfa72df6', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x07'}, u'device': u'bridge', u'type': u'interface'}, {u'device': u'console', u'specParams': {u'consoleType': u'serial', u'enableSocket': u'true'}, u'type': u'console', u'deviceId': u'09ab89ac-6c8b-457f-b3df-a51985433eff'}, {u'index': u'0', u'specParams': {}, u'deviceId': u'a3a92558-a502-464a-8ae9-57da982a8696', u'address': {u'function': u'0x2', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x01'}, u'device': u'usb', u'model': u'piix3-uhci', u'type': u'controller'}, {u'device': u'virtio-serial', u'specParams': {}, u'type': u'controller', u'deviceId': u'6452dc0e-adcb-4d99-bfef-158ade113ac8', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x08'}}], u'maxVCpus': u'16', u'display': u'qxl'}, recover=False) from=::ffff:10.34.100.46,56936, flow_id=d6fc6f3b-b3b2-466d-8fcd-c145d3cf645a (api:46)
2017-12-06 11:01:14,240+0100 INFO (jsonrpc/0) [vdsm.api] FINISH __init__ return=None from=::ffff:10.34.100.46,56936, flow_id=d6fc6f3b-b3b2-466d-8fcd-c145d3cf645a (api:52)
2017-12-06 11:01:14,280+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call VM.create succeeded in 0.05 seconds (__init__:539)
2017-12-06 11:01:14,283+0100 INFO (vm/f337aa89) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') VM wrapper has started (vm:1968)
2017-12-06 11:01:14,285+0100 INFO (vm/f337aa89) [vdsm.api] START getVolumeSize(sdUUID=u'961ea94a-aced-4dd0-a9f0-266ce1810177', spUUID=u'48ca3019-9dbf-4ef3-98e9-08105d396350', imgUUID=u'4a95614e-bf1d-407c-aa72-2df414abcb7a', volUUID=u'a0b6d5cb-db1e-4c25-aaaf-1bbee142c60b', options=None) from=internal, task_id=d3292b77-4659-4ec3-8656-149c09ab5365 (api:46)
2017-12-06 11:01:14,385+0100 INFO (vm/f337aa89) [vdsm.api] FINISH getVolumeSize return={'truesize': '2147483648', 'apparentsize': '2147483648'} from=internal, task_id=d3292b77-4659-4ec3-8656-149c09ab5365 (api:52)
2017-12-06 11:01:14,386+0100 INFO (vm/f337aa89) [vdsm.api] START getVolumeSize(sdUUID=u'961ea94a-aced-4dd0-a9f0-266ce1810177', spUUID=u'48ca3019-9dbf-4ef3-98e9-08105d396350', imgUUID=u'79d2fdfe-5545-4ba3-a133-4aec4684f0d7', volUUID=u'8699ea2d-3c23-47c6-b4be-eb377809bd15', options=None) from=internal, task_id=584959e3-beb4-4cba-94e0-107ae975ce69 (api:46)
2017-12-06 11:01:14,387+0100 INFO (vm/f337aa89) [vdsm.api] FINISH getVolumeSize return={'truesize': '194481487872', 'apparentsize': '194481487872'} from=internal, task_id=584959e3-beb4-4cba-94e0-107ae975ce69 (api:52)
2017-12-06 11:01:14,388+0100 INFO (vm/f337aa89) [vdsm.api] START getVolumeSize(sdUUID=u'961ea94a-aced-4dd0-a9f0-266ce1810177', spUUID=u'48ca3019-9dbf-4ef3-98e9-08105d396350', imgUUID=u'9b65853c-fc02-47d1-b310-93f37c7f5bfe', volUUID=u'aeeb8808-b0af-4c0f-918e-81402e5494de', options=None) from=internal, task_id=66b439cc-2689-4810-9ae4-fd9c229837f5 (api:46)
2017-12-06 11:01:14,388+0100 INFO (vm/f337aa89) [vdsm.api] FINISH getVolumeSize return={'truesize': '2147483648', 'apparentsize': '2147483648'} from=internal, task_id=66b439cc-2689-4810-9ae4-fd9c229837f5 (api:52)
2017-12-06 11:01:14,389+0100 INFO (vm/f337aa89) [vdsm.api] START getVolumeSize(sdUUID=u'961ea94a-aced-4dd0-a9f0-266ce1810177', spUUID=u'48ca3019-9dbf-4ef3-98e9-08105d396350', imgUUID=u'c98a3029-a06b-460f-bc11-550ac4a879ce', volUUID=u'6d07d624-b8fe-47d3-915a-1193fbfaa875', options=None) from=internal, task_id=e92f24ad-5d63-4402-bc7a-67e914688d86 (api:46)
2017-12-06 11:01:14,390+0100 INFO (vm/f337aa89) [vdsm.api] FINISH getVolumeSize return={'truesize': '4294967296', 'apparentsize': '4294967296'} from=internal, task_id=e92f24ad-5d63-4402-bc7a-67e914688d86 (api:52)
2017-12-06 11:01:14,391+0100 INFO (vm/f337aa89) [vds] prepared volume path: (clientIF:380)
2017-12-06 11:01:14,391+0100 INFO (vm/f337aa89) [vdsm.api] START prepareImage(sdUUID=u'961ea94a-aced-4dd0-a9f0-266ce1810177', spUUID=u'48ca3019-9dbf-4ef3-98e9-08105d396350', imgUUID=u'4a95614e-bf1d-407c-aa72-2df414abcb7a', leafUUID=u'a0b6d5cb-db1e-4c25-aaaf-1bbee142c60b', allowIllegal=False) from=internal, task_id=481934f3-a65e-4144-addd-e7e6f49ede85 (api:46)
2017-12-06 11:01:14,408+0100 INFO (jsonrpc/7) [vdsm.api] START repoStats(options=None) from=::ffff:10.34.100.46,56936, flow_id=2e957cc7, task_id=f73c9db3-e44d-49c5-b6dd-ba0cf807ef93 (api:46)
2017-12-06 11:01:14,409+0100 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return={u'0c9eb935-988d-4523-ae8d-bf3b34adddbb': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000263931', 'lastCheck': '6.7', 'valid': True}, u'f7d7a540-6f5a-420e-9fc3-94badbf6091f': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000330044', 'lastCheck': '5.8', 'valid': True}, u'961ea94a-aced-4dd0-a9f0-266ce1810177': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000595641', 'lastCheck': '5.8', 'valid': True}, u'af2589e5-fed5-465e-b528-b7cbca39c04f': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000469191', 'lastCheck': '6.5', 'valid': True}, u'a2decc25-bb4b-4b63-880a-09145367305c': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000476048', 'lastCheck': '5.5', 'valid': True}, u'58a6dca8-181e-4b66-944b-c9b4007370d3': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000309747', 'lastCheck': '5.8', 'valid': True}, u'5ef8572c-0ab5-4491-994a-e4c30230a525': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000422391', 'lastCheck': '6.7', 'valid': True}} from=::ffff:10.34.100.46,56936, flow_id=2e957cc7, task_id=f73c9db3-e44d-49c5-b6dd-ba0cf807ef93 (api:52)
2017-12-06 11:01:14,426+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.02 seconds (__init__:539)
2017-12-06 11:01:14,514+0100 INFO (vm/f337aa89) [vdsm.api] FINISH prepareImage error=Cannot prepare illegal volume: ('d81f213e-bd71-4ace-a573-5320819d1140',) from=internal, task_id=481934f3-a65e-4144-addd-e7e6f49ede85 (api:50)
2017-12-06 11:01:14,515+0100 ERROR (vm/f337aa89) [storage.TaskManager.Task] (Task='481934f3-a65e-4144-addd-e7e6f49ede85') Unexpected error (task:872)
Traceback (most recent call last):
File "/usr/share/vdsm/storage/task.py", line 879, in _run
return fn(*args, **kargs)
File "<string>", line 2, in prepareImage
File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 48, in method
ret = func(*args, **kwargs)
File "/usr/share/vdsm/storage/hsm.py", line 3143, in prepareImage
raise se.prepareIllegalVolumeError(volUUID)
prepareIllegalVolumeError: Cannot prepare illegal volume: ('d81f213e-bd71-4ace-a573-5320819d1140',)
2017-12-06 11:01:14,516+0100 INFO (vm/f337aa89) [storage.TaskManager.Task] (Task='481934f3-a65e-4144-addd-e7e6f49ede85') aborting: Task is aborted: "Cannot prepare illegal volume: ('d81f213e-bd71-4ace-a573-5320819d1140',)" - code 227 (task:1177)
2017-12-06 11:01:14,517+0100 ERROR (vm/f337aa89) [storage.Dispatcher] FINISH prepareImage error=Cannot prepare illegal volume: ('d81f213e-bd71-4ace-a573-5320819d1140',) (dispatcher:81)
2017-12-06 11:01:14,518+0100 ERROR (vm/f337aa89) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') The vm start process failed (vm:631)
Traceback (most recent call last):
File "/usr/share/vdsm/virt/vm.py", line 562, in _startUnderlyingVm
self._run()
File "/usr/share/vdsm/virt/vm.py", line 1978, in _run
self._preparePathsForDrives(dev_spec_map[hwclass.DISK])
File "/usr/share/vdsm/virt/vm.py", line 657, in _preparePathsForDrives
drive['path'] = self.cif.prepareVolumePath(drive, self.id)
File "/usr/share/vdsm/clientIF.py", line 321, in prepareVolumePath
raise vm.VolumeError(drive)
VolumeError: Bad volume specification {u'index': 0, u'domainID': u'961ea94a-aced-4dd0-a9f0-266ce1810177', 'reqsize': '0', u'format': u'cow', u'bootOrder': u'1', u'discard': False, u'volumeID': u'a0b6d5cb-db1e-4c25-aaaf-1bbee142c60b', 'apparentsize': '2147483648', u'imageID': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', u'specParams': {}, u'readonly': u'false', u'iface': u'virtio', u'optional': u'false', u'deviceId': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', 'truesize': '2147483648', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350', u'device': u'disk', u'shared': u'false', u'propagateErrors': u'off', u'type': u'disk'}
2017-12-06 11:01:14,521+0100 INFO (vm/f337aa89) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') Changed state to Down: Bad volume specification {u'index': 0, u'domainID': u'961ea94a-aced-4dd0-a9f0-266ce1810177', 'reqsize': '0', u'format': u'cow', u'bootOrder': u'1', u'discard': False, u'volumeID': u'a0b6d5cb-db1e-4c25-aaaf-1bbee142c60b', 'apparentsize': '2147483648', u'imageID': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', u'specParams': {}, u'readonly': u'false', u'iface': u'virtio', u'optional': u'false', u'deviceId': u'4a95614e-bf1d-407c-aa72-2df414abcb7a', 'truesize': '2147483648', u'poolID': u'48ca3019-9dbf-4ef3-98e9-08105d396350', u'device': u'disk', u'shared': u'false', u'propagateErrors': u'off', u'type': u'disk'} (code=1) (vm:1259)
2017-12-06 11:01:14,521+0100 INFO (vm/f337aa89) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') Stopping connection (guestagent:430)
2017-12-06 11:01:14,757+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-12-06 11:01:14,764+0100 INFO (jsonrpc/3) [vdsm.api] START destroy(gracefulAttempts=1) from=::ffff:10.34.100.46,56936 (api:46)
2017-12-06 11:01:14,765+0100 INFO (jsonrpc/3) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') Release VM resources (vm:4298)
2017-12-06 11:01:14,765+0100 WARN (jsonrpc/3) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') trying to set state to Powering down when already Down (vm:361)
2017-12-06 11:01:14,766+0100 INFO (jsonrpc/3) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') Stopping connection (guestagent:430)
2017-12-06 11:01:14,766+0100 INFO (jsonrpc/3) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') Stopping connection (guestagent:430)
2017-12-06 11:01:14,767+0100 WARN (jsonrpc/3) [root] File: /var/lib/libvirt/qemu/channels/f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec.com.redhat.rhevm.vdsm already removed (utils:120)
2017-12-06 11:01:14,768+0100 WARN (jsonrpc/3) [root] File: /var/lib/libvirt/qemu/channels/f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec.org.qemu.guest_agent.0 already removed (utils:120)
2017-12-06 11:01:14,768+0100 WARN (jsonrpc/3) [virt.vm] (vmId='f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') timestamp already removed from stats cache (vm:1781)
2017-12-06 11:01:14,769+0100 INFO (jsonrpc/3) [vdsm.api] START inappropriateDevices(thiefId=u'f337aa89-6e4e-4cc9-b78e-a5bd9ee946ec') from=::ffff:10.34.100.46,56936, task_id=d694f5d1-4f1b-48df-90b2-45db08bea9e7 (api:46)
2017-12-06 11:01:14,771+0100 INFO (jsonrpc/3) [vdsm.api] FINISH inappropriateDevices return=None from=::ffff:10.34.100.46,56936, task_id=d694f5d1-4f1b-48df-90b2-45db08bea9e7 (api:52)
2017-12-06 11:01:14,772+0100 INFO (jsonrpc/3) [vdsm.api] FINISH destroy return={'status': {'message': 'Done', 'code': 0}} from=::ffff:10.34.100.46,56936 (api:52)
2017-12-06 11:01:14,773+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.destroy succeeded in 0.01 seconds (__init__:539)
2017-12-06 11:01:16,776+0100 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=32b32784-f9dd-443e-9911-00553fc8c4d8 (api:46)
2017-12-06 11:01:16,776+0100 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'0c9eb935-988d-4523-ae8d-bf3b34adddbb': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000263931', 'lastCheck': '9.1', 'valid': True}, u'f7d7a540-6f5a-420e-9fc3-94badbf6091f': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000330044', 'lastCheck': '8.1', 'valid': True}, u'961ea94a-aced-4dd0-a9f0-266ce1810177': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000595641', 'lastCheck': '8.2', 'valid': True}, u'af2589e5-fed5-465e-b528-b7cbca39c04f': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000469191', 'lastCheck': '8.9', 'valid': True}, u'a2decc25-bb4b-4b63-880a-09145367305c': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000476048', 'lastCheck': '7.8', 'valid': True}, u'58a6dca8-181e-4b66-944b-c9b4007370d3': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000309747', 'lastCheck': '8.1', 'valid': True}, u'5ef8572c-0ab5-4491-994a-e4c30230a525': {'code': 0, 'actual': True, 'version': 4, 'acquired': False, 'delay': '0.000422391', 'lastCheck': '9.1', 'valid': True}} from=internal, task_id=32b32784-f9dd-443e-9911-00553fc8c4d8 (api:52)
2017-12-06 11:01:16,827+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-12-06 11:01:17,713+0100 INFO (monitor/5ef8572) [storage.Monitor] Host id for domain 5ef8572c-0ab5-4491-994a-e4c30230a525 successfully acquired (id: 9) (monitor:449)
2017-12-06 11:01:18,581+0100 INFO (monitor/961ea94) [storage.Monitor] Host id for domain 961ea94a-aced-4dd0-a9f0-266ce1810177 successfully acquired (id: 9) (monitor:449)
2017-12-06 11:01:18,648+0100 INFO (monitor/58a6dca) [storage.Monitor] Host id for domain 58a6dca8-181e-4b66-944b-c9b4007370d3 successfully acquired (id: 9) (monitor:449)
2017-12-06 11:01:18,649+0100 INFO (monitor/f7d7a54) [storage.Monitor] Host id for domain f7d7a540-6f5a-420e-9fc3-94badbf6091f successfully acquired (id: 9) (monitor:449)
2017-12-06 11:01:18,931+0100 INFO (monitor/a2decc2) [storage.Monitor] Host id for domain a2decc25-bb4b-4b63-880a-09145367305c successfully acquired (id: 9) (monitor:449)
_______________________________________________
Users mailing list
Users@ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
