6:20 p.m.
I believe I'm following the instructions for installing a Windows 7 test
VM from the ovirt quick start guide, yet it's not working.
I create the VM, then when I go to "Run Once", select "Atttach Floppy
[sysprep]" and "Attach CD", then click OK, the VM stops with an error:
"Failed to run VM wintest".
engine:
2015-04-16 10:49:01,678 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-12)
[5704c4a] Lock Acquired to object EngineLock [exclusiveLocks= key:
454a5771-2780-4a0b-904a-e2caf08f7bdc value: VM
, sharedLocks= ]
2015-04-16 10:49:01,706 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-12) START, IsVmDuringInitiatingVDSCommand( vmId =
454a5771-2780-4a0b-904a-e2caf08f7bdc), log id: 3691d118
2015-04-16 10:49:01,707 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--127.0.0.1-8702-12) FINISH, IsVmDuringInitiatingVDSCommand, return:
false, log id: 3691d118
2015-04-16 10:49:01,801 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-12)
Running command: RunVmOnceCommand internal: false. Entities affected :
ID: 454a5771-2780-4a0b-904a-e2caf08f7bdc Type: VMAction group
VM_BASIC_OPERATIONS with role type USER
2015-04-16 10:49:01,874 INFO
[org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit]
(ajp--127.0.0.1-8702-12) Started HA reservation scoring method
2015-04-16 10:49:01,905 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(ajp--127.0.0.1-8702-12) Running VM with attached cd
en_windows_7_professional_x64_dvd_x15-65805.iso
2015-04-16 10:49:01,923 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(ajp--127.0.0.1-8702-12) START, UpdateVmDynamicDataVDSCommand(HostName =
virt2, HostId = 1d1d1fbb-3067-4703-8b51-e0a231d344e6,
vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@1689f4a7),
log id: 7d1288a3
2015-04-16 10:49:01,934 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(ajp--127.0.0.1-8702-12) FINISH, UpdateVmDynamicDataVDSCommand, log id:
7d1288a3
2015-04-16 10:49:01,956 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(ajp--127.0.0.1-8702-12) START, IsoPrefixVDSCommand(HostName = virt2,
HostId = 1d1d1fbb-3067-4703-8b51-e0a231d344e6,
storagePoolId=a5a001c7-bf45-444d-8e9c-884e6fedec24), log id: 527f003c
2015-04-16 10:49:01,957 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(ajp--127.0.0.1-8702-12) FINISH, IsoPrefixVDSCommand, return:
/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111,
log id: 527f003c
2015-04-16 10:49:01,975 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(ajp--127.0.0.1-8702-12) START, CreateVmVDSCommand(HostName = virt2,
HostId = 1d1d1fbb-3067-4703-8b51-e0a231d344e6,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, vm=VM [wintest]), log id:
3dd1813c
2015-04-16 10:49:01,979 WARN
[org.ovirt.engine.core.vdsbroker.vdsbroker.SysprepHandler]
(ajp--127.0.0.1-8702-12) Could not find value for key user. Going to use
default value of:
2015-04-16 10:49:01,996 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand]
(ajp--127.0.0.1-8702-12)
START, CreateVmFromSysPrepVDSCommand(HostName = virt2, HostId =
1d1d1fbb-3067-4703-8b51-e0a231d344e6,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, vm=VM [wintest]), log id:
4640cbb3
2015-04-16 10:49:02,017 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
(ajp--127.0.0.1-8702-12)
org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand
kvmEnable=true,keyboardLayout=en-us,nice=0,displayNetwork=yorku,copyPasteEnable=true,timeOffset=3600,transparentHugePages=true,vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=2048,smp=2,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=1365,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,maxVCpus=16,hypervEnable=true,devices=[{shared=false,
iface=fdc, index=0,
specParams={vmPayload={file={"Unattend.xml"=PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0idXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9IndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAgICAgICAgICAgICAgICAgICA8S2V5PjwhW0NEQVRBW11dPjwvS2V5Pg0KICAgICAgICAgICAgICAgIDwvUHJvZHVjdEtleT4NCiAgICAgICAgICAgICAgICA8QWNjZXB0RXVsYT50cnVlPC9BY2NlcHRFdWxhPg0KICAgICAgICAgICAgICAgIDxGdWxsTmFtZT4idXNlciI8L0Z1bGxOYW1lPg0KICAgICAgICAgICAgICAgIDxPcmdhbml6YXRpb24+PCFbQ0RBVEFbZWVjcy55b3JrdS5jYV1dPjwvT3JnYW5pemF0aW9uPg0KICAgICAgICAgICAgPC9Vc2VyRGF0YT4NCiAgICAgICAgICAgIDxJbWFnZUluc3RhbGw+DQogICAgICAgICAgICAgICAgPE9TSW1hZ2U+DQogICAgICAgICAgICAgICAgICAgIDxJbnN0YWxsVG9BdmFpbGFibGVQYXJ0aXRpb24+dHJ1ZTwvSW5zdGFsbFRvQXZhaWxhYmxlUGFydGl0aW9uPg0KICAgICAgICAgICAgICAgIDwvT1NJbWFnZT4NCiAgICAgICAgICAgIDwvSW1hZ2VJbnN0YWxsPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUtV2luUEUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFNldHVwVUlMYW5ndWFnZT4NCiAgICAgICAgICAgICAgICA8VUlMYW5ndWFnZT48IVtDREFUQVtlbl9VU11dPjwvVUlMYW5ndWFnZT4NCiAgICAgICAgICAgIDwvU2V0dXBVSUxhbmd1YWdlPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPjwhW0NEQVRBW2VuX1VTXV0+PC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVzZXJMb2NhbGU+PCFbQ0RBVEFbZW5fVVNdXT48L1VzZXJMb2NhbGU+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGluZ3M+DQogICAgPHNldHRpbmdzIHBhc3M9InNwZWNpYWxpemUiPg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxEaXNwbGF5Pg0KICAgICAgICAgICAgICAgIDxDb2xvckRlcHRoPjMyPC9Db2xvckRlcHRoPg0KICAgICAgICAgICAgICAgIDxEUEk+OTY8L0RQST4NCiAgICAgICAgICAgICAgICA8SG9yaXpvbnRhbFJlc29sdXRpb24+MTAyNDwvSG9yaXpvbnRhbFJlc29sdXRpb24+DQogICAgICAgICAgICAgICAgPFJlZnJlc2hSYXRlPjc1PC9SZWZyZXNoUmF0ZT4NCiAgICAgICAgICAgICAgICA8VmVydGljYWxSZXNvbHV0aW9uPjc2ODwvVmVydGljYWxSZXNvbHV0aW9uPg0KICAgICAgICAgICAgPC9EaXNwbGF5Pg0KICAgICAgICAgICAgPENvbXB1dGVyTmFtZT48IVtDREFUQVt3aW50ZXN0XV0+PC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGltZVpvbmU+PCFbQ0RBVEFbR01UIFN0YW5kYXJkIFRpbWVdXT48L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAgICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48IVtDREFUQVtdXT48L0RvbWFpbj4NCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwhW0NEQVRBW11dPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgIDxVc2VybmFtZT48IVtDREFUQVtdXT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlhbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PCFbQ0RBVEFbXV0+PC9Kb2luRG9tYWluPg0KICAgICAgICAgICAgICAgIDxNYWNoaW5lT2JqZWN0T1U+PCFbQ0RBVEFbXV0+PC9NYWNoaW5lT2JqZWN0T1U+DQogICAgICAgICAgICA8L0lkZW50aWZpY2F0aW9uPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxzZXR0aW5ncyBwYXNzPSJvb2JlU3lzdGVtIj4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPE9PQkU+DQogICAgICAgICAgICAgICAgPFByb3RlY3RZb3VyUEM+MjwvUHJvdGVjdFlvdXJQQz4NCiAgICAgICAgICAgICAgICA8TmV0d29ya0xvY2F0aW9uPldvcms8L05ldHdvcmtMb2NhdGlvbj4NCiAgICAgICAgICAgICAgICA8SGlkZUVVTEFQYWdlPnRydWU8L0hpZGVFVUxBUGFnZT4NCiAgICAgICAgICAgIDwvT09CRT4NCiAgICAgICAgICAgIDxVc2VyQWNjb3VudHM+DQogICAgICAgICAgICAgICAgPEFkbWluaXN0cmF0b3JQYXNzd29yZD4NCiAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgIDxQbGFpblRleHQ+dHJ1ZTwvUGxhaW5UZXh0Pg0KICAgICAgICAgICAgICAgIDwvQWRtaW5pc3RyYXRvclBhc3N3b3JkPg0KICAgICAgICAgICAgICAgIDxMb2NhbEFjY291bnRzPg0KICAgICAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50IHdjbTphY3Rpb249ImFkZCI+DQogICAgICAgICAgICAgICAgICAgICAgICA8UGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFBsYWluVGV4dD50cnVlPC9QbGFpblRleHQ+DQogICAgICAgICAgICAgICAgICAgICAgICA8L1Bhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgPEdyb3VwPmFkbWluaXN0cmF0b3JzPC9Hcm91cD4NCiAgICAgICAgICAgICAgICAgICAgICAgIDxOYW1lPnVzZXI8L05hbWU+DQogICAgICAgICAgICAgICAgICAgICAgICA8RGlzcGxheU5hbWU+dXNlcjwvRGlzcGxheU5hbWU+DQogICAgICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50Pg0KICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgIDwvVXNlckFjY291bnRzPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxjcGk6b2ZmbGluZUltYWdlIGNwaTpzb3VyY2U9IndpbTpkOi9zb3VyY2VzL2luc3RhbGwud2ltI1dpbmRvd3MgNyBFTlRFUlBSSVNFIiB4bWxuczpjcGk9InVybjpzY2hlbWFzLW1pY3Jvc29mdC1jb206Y3BpIi8+DQo8L3VuYXR0ZW5kPg0K}}},
path=, device=floppy, type=disk, readonly=true,
deviceId=9c464dc7-9a1f-4188-899a-8af2f5538b2c}, {specParams={vram=32768,
heads=1}, device=qxl, type=video,
deviceId=c150cc76-ff34-4c8a-9770-f049f6d33590}, {shared=false,
bootOrder=2, iface=ide, index=2, specParams={},
path=/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111/en_windows_7_professional_x64_dvd_x15-65805.iso,
device=cdrom, type=disk, readonly=true,
deviceId=f52149b3-2733-419d-8cda-d67f2755d6b7}, {shared=false, index=0,
volumeID=d9973802-1983-4236-9a66-1d2d1957799e, propagateErrors=off,
format=raw, type=disk, iface=virtio, bootOrder=1,
domainID=c6628e37-903e-427c-bedb-f7fe16267778,
imageID=1b105659-3f4c-4fa2-b4e3-e8fd15501eb3, specParams={},
optional=false, device=disk,
poolID=a5a001c7-bf45-444d-8e9c-884e6fedec24, readonly=false,
deviceId=1b105659-3f4c-4fa2-b4e3-e8fd15501eb3}, {bootOrder=3,
nicModel=pv, specParams={outbound={}, inbound={}},
macAddr=00:1a:4a:5d:5c:04, device=bridge, linkActive=true,
type=interface, filter=vdsm-no-mac-spoofing, network=yorku,
deviceId=d58aadde-27e2-4c76-9aa7-5f9053f0c415}, {specParams={},
device=ich6, type=sound, deviceId=05120a5d-7633-4c74-94cf-2f6ef70e1eef},
{specParams={model=virtio}, device=memballoon, type=balloon,
deviceId=cf2be11d-5f87-4c62-9877-625a90d75d3d}, {index=0,
model=virtio-scsi, specParams={}, device=scsi, type=controller,
deviceId=2c78adb0-dce6-49fc-88b8-46481d459aa2}, {specParams={},
device=virtio-serial, type=controller,
deviceId=ddee73fb-2605-4794-ab40-f4cf2ed57a1f}],vmName=wintest,cpuType=SandyBridge,fileTransferEnable=true
2015-04-16 10:49:02,115 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand]
(ajp--127.0.0.1-8702-12)
FINISH, CreateVmFromSysPrepVDSCommand, log id: 4640cbb3
2015-04-16 10:49:02,134 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(ajp--127.0.0.1-8702-12) FINISH, CreateVmVDSCommand, return:
WaitForLaunch, log id: 3dd1813c
2015-04-16 10:49:02,135 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--127.0.0.1-8702-12)
Lock freed to object EngineLock [exclusiveLocks= key:
454a5771-2780-4a0b-904a-e2caf08f7bdc value: VM
, sharedLocks= ]
2015-04-16 10:49:02,151 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp--127.0.0.1-8702-12) Correlation ID: 5704c4a, Job ID:
9049a63c-90b1-4e5f-baa5-ae507db4933d, Call Stack: null, Custom Event ID:
-1, Message: VM wintest was started by admin@internal (Host: virt2).
2015-04-16 10:49:02,347 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(DefaultQuartzScheduler_Worker-60) [756abce] START,
DestroyVDSCommand(HostName = virt2, HostId =
1d1d1fbb-3067-4703-8b51-e0a231d344e6,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, force=false, secondsToWait=0,
gracefully=false, reason=), log id: 7ad9a1fb
2015-04-16 10:49:02,356 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(DefaultQuartzScheduler_Worker-60) [756abce] FINISH, DestroyVDSCommand,
log id: 7ad9a1fb
2015-04-16 10:49:02,393 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-60) [756abce] Correlation ID: null, Call
Stack: null, Custom Event ID: -1, Message: VM wintest is down with
error. Exit message: [Errno 5] could not create floppy file: code 1, out
mkfs.fat 3.0.20 (12 Jun 2013)
err mkfs.msdos: unable to create
/var/run/vdsm/payload/454a5771-2780-4a0b-904a-e2caf08f7bdc.ea432ba28823b1a56eaf6b2187ed863b.img
.
2015-04-16 10:49:02,397 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-60) [756abce] Running on vds during rerun
failed vm: null
2015-04-16 10:49:02,399 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-60) [756abce] VM wintest
(454a5771-2780-4a0b-904a-e2caf08f7bdc) is running in db and not running
in VDS virt2
2015-04-16 10:49:02,419 ERROR
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-60) [756abce] Rerun vm
454a5771-2780-4a0b-904a-e2caf08f7bdc. Called from vds virt2
2015-04-16 10:49:02,435 WARN
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(org.ovirt.thread.pool-8-thread-19) [756abce] Correlation ID: 5704c4a,
Job ID: 9049a63c-90b1-4e5f-baa5-ae507db4933d, Call Stack: null, Custom
Event ID: -1, Message: Failed to run VM wintest on Host virt2.
2015-04-16 10:49:02,452 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand]
(org.ovirt.thread.pool-8-thread-19) [756abce] Lock Acquired to object
EngineLock [exclusiveLocks= key: 454a5771-2780-4a0b-904a-e2caf08f7bdc
value: VM
, sharedLocks= ]
2015-04-16 10:49:02,472 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(org.ovirt.thread.pool-8-thread-19) START,
IsVmDuringInitiatingVDSCommand( vmId =
454a5771-2780-4a0b-904a-e2caf08f7bdc), log id: 218c1b1f
2015-04-16 10:49:02,473 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(org.ovirt.thread.pool-8-thread-19) FINISH,
IsVmDuringInitiatingVDSCommand, return: false, log id: 218c1b1f
2015-04-16 10:49:02,551 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand]
(org.ovirt.thread.pool-8-thread-19) Running command: RunVmOnceCommand
internal: false. Entities affected : ID:
454a5771-2780-4a0b-904a-e2caf08f7bdc Type: VMAction group
VM_BASIC_OPERATIONS with role type USER
2015-04-16 10:49:02,621 INFO
[org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit]
(org.ovirt.thread.pool-8-thread-19) Started HA reservation scoring method
2015-04-16 10:49:02,647 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(org.ovirt.thread.pool-8-thread-19) Running VM with attached cd
en_windows_7_professional_x64_dvd_x15-65805.iso
2015-04-16 10:49:02,662 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(org.ovirt.thread.pool-8-thread-19) START,
UpdateVmDynamicDataVDSCommand(HostName = virt1, HostId =
e21cf0b9-a7e7-41e7-96d0-27006b42ad84,
vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@64752476),
log id: f866c69
2015-04-16 10:49:02,668 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(org.ovirt.thread.pool-8-thread-19) FINISH,
UpdateVmDynamicDataVDSCommand, log id: f866c69
2015-04-16 10:49:02,688 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(org.ovirt.thread.pool-8-thread-19) START, IsoPrefixVDSCommand(HostName
= virt1, HostId = e21cf0b9-a7e7-41e7-96d0-27006b42ad84,
storagePoolId=a5a001c7-bf45-444d-8e9c-884e6fedec24), log id: 60ee7482
2015-04-16 10:49:02,690 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(org.ovirt.thread.pool-8-thread-19) FINISH, IsoPrefixVDSCommand, return:
/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111,
log id: 60ee7482
2015-04-16 10:49:02,713 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(org.ovirt.thread.pool-8-thread-19) START, CreateVmVDSCommand(HostName =
virt1, HostId = e21cf0b9-a7e7-41e7-96d0-27006b42ad84,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, vm=VM [wintest]), log id:
35917482
2015-04-16 10:49:02,718 WARN
[org.ovirt.engine.core.vdsbroker.vdsbroker.SysprepHandler]
(org.ovirt.thread.pool-8-thread-19) Could not find value for key user.
Going to use default value of:
2015-04-16 10:49:02,736 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand]
(org.ovirt.thread.pool-8-thread-19)
START, CreateVmFromSysPrepVDSCommand(HostName = virt1, HostId =
e21cf0b9-a7e7-41e7-96d0-27006b42ad84,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, vm=VM [wintest]), log id:
2f8b27c8
2015-04-16 10:49:02,751 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
(org.ovirt.thread.pool-8-thread-19)
org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand
kvmEnable=true,keyboardLayout=en-us,nice=0,displayNetwork=yorku,copyPasteEnable=true,timeOffset=3600,transparentHugePages=true,vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=2048,smp=2,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=1365,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,maxVCpus=16,hypervEnable=true,devices=[{shared=false,
iface=fdc, index=0,
specParams={vmPayload={file={"Unattend.xml"=PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0idXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9IndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAgICAgICAgICAgICAgICAgICA8S2V5PjwhW0NEQVRBW11dPjwvS2V5Pg0KICAgICAgICAgICAgICAgIDwvUHJvZHVjdEtleT4NCiAgICAgICAgICAgICAgICA8QWNjZXB0RXVsYT50cnVlPC9BY2NlcHRFdWxhPg0KICAgICAgICAgICAgICAgIDxGdWxsTmFtZT4idXNlciI8L0Z1bGxOYW1lPg0KICAgICAgICAgICAgICAgIDxPcmdhbml6YXRpb24+PCFbQ0RBVEFbZWVjcy55b3JrdS5jYV1dPjwvT3JnYW5pemF0aW9uPg0KICAgICAgICAgICAgPC9Vc2VyRGF0YT4NCiAgICAgICAgICAgIDxJbWFnZUluc3RhbGw+DQogICAgICAgICAgICAgICAgPE9TSW1hZ2U+DQogICAgICAgICAgICAgICAgICAgIDxJbnN0YWxsVG9BdmFpbGFibGVQYXJ0aXRpb24+dHJ1ZTwvSW5zdGFsbFRvQXZhaWxhYmxlUGFydGl0aW9uPg0KICAgICAgICAgICAgICAgIDwvT1NJbWFnZT4NCiAgICAgICAgICAgIDwvSW1hZ2VJbnN0YWxsPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUtV2luUEUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFNldHVwVUlMYW5ndWFnZT4NCiAgICAgICAgICAgICAgICA8VUlMYW5ndWFnZT48IVtDREFUQVtlbl9VU11dPjwvVUlMYW5ndWFnZT4NCiAgICAgICAgICAgIDwvU2V0dXBVSUxhbmd1YWdlPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPjwhW0NEQVRBW2VuX1VTXV0+PC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVzZXJMb2NhbGU+PCFbQ0RBVEFbZW5fVVNdXT48L1VzZXJMb2NhbGU+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGluZ3M+DQogICAgPHNldHRpbmdzIHBhc3M9InNwZWNpYWxpemUiPg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxEaXNwbGF5Pg0KICAgICAgICAgICAgICAgIDxDb2xvckRlcHRoPjMyPC9Db2xvckRlcHRoPg0KICAgICAgICAgICAgICAgIDxEUEk+OTY8L0RQST4NCiAgICAgICAgICAgICAgICA8SG9yaXpvbnRhbFJlc29sdXRpb24+MTAyNDwvSG9yaXpvbnRhbFJlc29sdXRpb24+DQogICAgICAgICAgICAgICAgPFJlZnJlc2hSYXRlPjc1PC9SZWZyZXNoUmF0ZT4NCiAgICAgICAgICAgICAgICA8VmVydGljYWxSZXNvbHV0aW9uPjc2ODwvVmVydGljYWxSZXNvbHV0aW9uPg0KICAgICAgICAgICAgPC9EaXNwbGF5Pg0KICAgICAgICAgICAgPENvbXB1dGVyTmFtZT48IVtDREFUQVt3aW50ZXN0XV0+PC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGltZVpvbmU+PCFbQ0RBVEFbR01UIFN0YW5kYXJkIFRpbWVdXT48L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAgICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48IVtDREFUQVtdXT48L0RvbWFpbj4NCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwhW0NEQVRBW11dPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgIDxVc2VybmFtZT48IVtDREFUQVtdXT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlhbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PCFbQ0RBVEFbXV0+PC9Kb2luRG9tYWluPg0KICAgICAgICAgICAgICAgIDxNYWNoaW5lT2JqZWN0T1U+PCFbQ0RBVEFbXV0+PC9NYWNoaW5lT2JqZWN0T1U+DQogICAgICAgICAgICA8L0lkZW50aWZpY2F0aW9uPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxzZXR0aW5ncyBwYXNzPSJvb2JlU3lzdGVtIj4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPE9PQkU+DQogICAgICAgICAgICAgICAgPFByb3RlY3RZb3VyUEM+MjwvUHJvdGVjdFlvdXJQQz4NCiAgICAgICAgICAgICAgICA8TmV0d29ya0xvY2F0aW9uPldvcms8L05ldHdvcmtMb2NhdGlvbj4NCiAgICAgICAgICAgICAgICA8SGlkZUVVTEFQYWdlPnRydWU8L0hpZGVFVUxBUGFnZT4NCiAgICAgICAgICAgIDwvT09CRT4NCiAgICAgICAgICAgIDxVc2VyQWNjb3VudHM+DQogICAgICAgICAgICAgICAgPEFkbWluaXN0cmF0b3JQYXNzd29yZD4NCiAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgIDxQbGFpblRleHQ+dHJ1ZTwvUGxhaW5UZXh0Pg0KICAgICAgICAgICAgICAgIDwvQWRtaW5pc3RyYXRvclBhc3N3b3JkPg0KICAgICAgICAgICAgICAgIDxMb2NhbEFjY291bnRzPg0KICAgICAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50IHdjbTphY3Rpb249ImFkZCI+DQogICAgICAgICAgICAgICAgICAgICAgICA8UGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFBsYWluVGV4dD50cnVlPC9QbGFpblRleHQ+DQogICAgICAgICAgICAgICAgICAgICAgICA8L1Bhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgPEdyb3VwPmFkbWluaXN0cmF0b3JzPC9Hcm91cD4NCiAgICAgICAgICAgICAgICAgICAgICAgIDxOYW1lPnVzZXI8L05hbWU+DQogICAgICAgICAgICAgICAgICAgICAgICA8RGlzcGxheU5hbWU+dXNlcjwvRGlzcGxheU5hbWU+DQogICAgICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50Pg0KICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgIDwvVXNlckFjY291bnRzPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxjcGk6b2ZmbGluZUltYWdlIGNwaTpzb3VyY2U9IndpbTpkOi9zb3VyY2VzL2luc3RhbGwud2ltI1dpbmRvd3MgNyBFTlRFUlBSSVNFIiB4bWxuczpjcGk9InVybjpzY2hlbWFzLW1pY3Jvc29mdC1jb206Y3BpIi8+DQo8L3VuYXR0ZW5kPg0K}}},
path=, device=floppy, type=disk, readonly=true,
deviceId=dd6f617c-2b8a-4c41-93f1-67a4617e301e}, {specParams={vram=32768,
heads=1}, device=qxl, type=video,
deviceId=c150cc76-ff34-4c8a-9770-f049f6d33590}, {shared=false,
bootOrder=2, iface=ide, index=2, specParams={},
path=/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111/en_windows_7_professional_x64_dvd_x15-65805.iso,
device=cdrom, type=disk, readonly=true,
deviceId=45db0196-d16f-4820-8187-9628396e746c}, {shared=false, index=0,
volumeID=d9973802-1983-4236-9a66-1d2d1957799e, propagateErrors=off,
format=raw, type=disk, iface=virtio, bootOrder=1,
domainID=c6628e37-903e-427c-bedb-f7fe16267778,
imageID=1b105659-3f4c-4fa2-b4e3-e8fd15501eb3, specParams={},
optional=false, device=disk,
poolID=a5a001c7-bf45-444d-8e9c-884e6fedec24, readonly=false,
deviceId=1b105659-3f4c-4fa2-b4e3-e8fd15501eb3}, {bootOrder=3,
nicModel=pv, specParams={outbound={}, inbound={}},
macAddr=00:1a:4a:5d:5c:04, device=bridge, linkActive=true,
type=interface, filter=vdsm-no-mac-spoofing, network=yorku,
deviceId=d58aadde-27e2-4c76-9aa7-5f9053f0c415}, {specParams={},
device=ich6, type=sound, deviceId=05120a5d-7633-4c74-94cf-2f6ef70e1eef},
{specParams={model=virtio}, device=memballoon, type=balloon,
deviceId=cf2be11d-5f87-4c62-9877-625a90d75d3d}, {index=0,
model=virtio-scsi, specParams={}, device=scsi, type=controller,
deviceId=2c78adb0-dce6-49fc-88b8-46481d459aa2}, {specParams={},
device=virtio-serial, type=controller,
deviceId=ddee73fb-2605-4794-ab40-f4cf2ed57a1f}],vmName=wintest,cpuType=SandyBridge,fileTransferEnable=true
2015-04-16 10:49:02,884 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand]
(org.ovirt.thread.pool-8-thread-19)
FINISH, CreateVmFromSysPrepVDSCommand, log id: 2f8b27c8
2015-04-16 10:49:02,901 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(org.ovirt.thread.pool-8-thread-19) FINISH, CreateVmVDSCommand, return:
WaitForLaunch, log id: 35917482
2015-04-16 10:49:02,903 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand]
(org.ovirt.thread.pool-8-thread-19) Lock freed to object EngineLock
[exclusiveLocks= key: 454a5771-2780-4a0b-904a-e2caf08f7bdc value: VM
, sharedLocks= ]
2015-04-16 10:49:02,909 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(org.ovirt.thread.pool-8-thread-19) Correlation ID: 5704c4a, Job ID:
9049a63c-90b1-4e5f-baa5-ae507db4933d, Call Stack: null, Custom Event ID:
-1, Message: VM wintest was started by admin@internal (Host: virt1).
2015-04-16 10:49:04,778 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(DefaultQuartzScheduler_Worker-30) START, DestroyVDSCommand(HostName =
virt1, HostId = e21cf0b9-a7e7-41e7-96d0-27006b42ad84,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, force=false, secondsToWait=0,
gracefully=false, reason=), log id: 7a08a32c
2015-04-16 10:49:04,787 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(DefaultQuartzScheduler_Worker-30) FINISH, DestroyVDSCommand, log id:
7a08a32c
2015-04-16 10:49:04,818 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-30) Correlation ID: null, Call Stack:
null, Custom Event ID: -1, Message: VM wintest is down with error. Exit
message: [Errno 5] could not create floppy file: code 1, out mkfs.fat
3.0.20 (12 Jun 2013)
err mkfs.msdos: unable to create
/var/run/vdsm/payload/454a5771-2780-4a0b-904a-e2caf08f7bdc.ea432ba28823b1a56eaf6b2187ed863b.img
.
2015-04-16 10:49:04,823 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-30) Running on vds during rerun failed
vm: null
2015-04-16 10:49:04,825 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-30) VM wintest
(454a5771-2780-4a0b-904a-e2caf08f7bdc) is running in db and not running
in VDS virt1
2015-04-16 10:49:04,894 ERROR
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-30) Rerun vm
454a5771-2780-4a0b-904a-e2caf08f7bdc. Called from vds virt1
2015-04-16 10:49:04,910 WARN
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(org.ovirt.thread.pool-8-thread-22) Correlation ID: 5704c4a, Job ID:
9049a63c-90b1-4e5f-baa5-ae507db4933d, Call Stack: null, Custom Event ID:
-1, Message: Failed to run VM wintest on Host virt1.
2015-04-16 10:49:04,936 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand]
(org.ovirt.thread.pool-8-thread-22) Lock Acquired to object EngineLock
[exclusiveLocks= key: 454a5771-2780-4a0b-904a-e2caf08f7bdc value: VM
, sharedLocks= ]
2015-04-16 10:49:04,954 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(org.ovirt.thread.pool-8-thread-22) START,
IsVmDuringInitiatingVDSCommand( vmId =
454a5771-2780-4a0b-904a-e2caf08f7bdc), log id: 54f6cec
2015-04-16 10:49:04,956 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(org.ovirt.thread.pool-8-thread-22) FINISH,
IsVmDuringInitiatingVDSCommand, return: false, log id: 54f6cec
2015-04-16 10:49:05,035 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand]
(org.ovirt.thread.pool-8-thread-22) Running command: RunVmOnceCommand
internal: false. Entities affected : ID:
454a5771-2780-4a0b-904a-e2caf08f7bdc Type: VMAction group
VM_BASIC_OPERATIONS with role type USER
2015-04-16 10:49:05,124 INFO [org.ovirt.engine.core.bll.RunVmCommand]
(org.ovirt.thread.pool-8-thread-22) Running VM with attached cd
en_windows_7_professional_x64_dvd_x15-65805.iso
2015-04-16 10:49:05,140 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(org.ovirt.thread.pool-8-thread-22) START,
UpdateVmDynamicDataVDSCommand(HostName = virt3, HostId =
75384239-3bb4-4627-8afc-53b8d074b56c,
vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic@1b104e98),
log id: 59fe6783
2015-04-16 10:49:05,151 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand]
(org.ovirt.thread.pool-8-thread-22) FINISH,
UpdateVmDynamicDataVDSCommand, log id: 59fe6783
2015-04-16 10:49:05,167 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(org.ovirt.thread.pool-8-thread-22) START, IsoPrefixVDSCommand(HostName
= virt3, HostId = 75384239-3bb4-4627-8afc-53b8d074b56c,
storagePoolId=a5a001c7-bf45-444d-8e9c-884e6fedec24), log id: 135544b2
2015-04-16 10:49:05,169 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand]
(org.ovirt.thread.pool-8-thread-22) FINISH, IsoPrefixVDSCommand, return:
/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111,
log id: 135544b2
2015-04-16 10:49:05,187 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(org.ovirt.thread.pool-8-thread-22) START, CreateVmVDSCommand(HostName =
virt3, HostId = 75384239-3bb4-4627-8afc-53b8d074b56c,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, vm=VM [wintest]), log id:
4a30c0fc
2015-04-16 10:49:05,191 WARN
[org.ovirt.engine.core.vdsbroker.vdsbroker.SysprepHandler]
(org.ovirt.thread.pool-8-thread-22) Could not find value for key user.
Going to use default value of:
2015-04-16 10:49:05,208 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand]
(org.ovirt.thread.pool-8-thread-22)
START, CreateVmFromSysPrepVDSCommand(HostName = virt3, HostId =
75384239-3bb4-4627-8afc-53b8d074b56c,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, vm=VM [wintest]), log id:
1e76602c
2015-04-16 10:49:05,219 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
(org.ovirt.thread.pool-8-thread-22)
org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand
kvmEnable=true,keyboardLayout=en-us,nice=0,displayNetwork=yorku,copyPasteEnable=true,timeOffset=3600,transparentHugePages=true,vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc,acpiEnable=true,custom={},spiceSslCipherSuite=DEFAULT,memSize=2048,smp=2,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=1365,display=qxl,smartcardEnable=false,bootMenuEnable=false,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,smpCoresPerSocket=1,maxVCpus=16,hypervEnable=true,devices=[{shared=false,
iface=fdc, index=0,
specParams={vmPayload={file={"Unattend.xml"=PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0idXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9IndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAgICAgICAgICAgICAgICAgICA8S2V5PjwhW0NEQVRBW11dPjwvS2V5Pg0KICAgICAgICAgICAgICAgIDwvUHJvZHVjdEtleT4NCiAgICAgICAgICAgICAgICA8QWNjZXB0RXVsYT50cnVlPC9BY2NlcHRFdWxhPg0KICAgICAgICAgICAgICAgIDxGdWxsTmFtZT4idXNlciI8L0Z1bGxOYW1lPg0KICAgICAgICAgICAgICAgIDxPcmdhbml6YXRpb24+PCFbQ0RBVEFbZWVjcy55b3JrdS5jYV1dPjwvT3JnYW5pemF0aW9uPg0KICAgICAgICAgICAgPC9Vc2VyRGF0YT4NCiAgICAgICAgICAgIDxJbWFnZUluc3RhbGw+DQogICAgICAgICAgICAgICAgPE9TSW1hZ2U+DQogICAgICAgICAgICAgICAgICAgIDxJbnN0YWxsVG9BdmFpbGFibGVQYXJ0aXRpb24+dHJ1ZTwvSW5zdGFsbFRvQXZhaWxhYmxlUGFydGl0aW9uPg0KICAgICAgICAgICAgICAgIDwvT1NJbWFnZT4NCiAgICAgICAgICAgIDwvSW1hZ2VJbnN0YWxsPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUtV2luUEUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFNldHVwVUlMYW5ndWFnZT4NCiAgICAgICAgICAgICAgICA8VUlMYW5ndWFnZT48IVtDREFUQVtlbl9VU11dPjwvVUlMYW5ndWFnZT4NCiAgICAgICAgICAgIDwvU2V0dXBVSUxhbmd1YWdlPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPjwhW0NEQVRBW2VuX1VTXV0+PC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVzZXJMb2NhbGU+PCFbQ0RBVEFbZW5fVVNdXT48L1VzZXJMb2NhbGU+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGluZ3M+DQogICAgPHNldHRpbmdzIHBhc3M9InNwZWNpYWxpemUiPg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxEaXNwbGF5Pg0KICAgICAgICAgICAgICAgIDxDb2xvckRlcHRoPjMyPC9Db2xvckRlcHRoPg0KICAgICAgICAgICAgICAgIDxEUEk+OTY8L0RQST4NCiAgICAgICAgICAgICAgICA8SG9yaXpvbnRhbFJlc29sdXRpb24+MTAyNDwvSG9yaXpvbnRhbFJlc29sdXRpb24+DQogICAgICAgICAgICAgICAgPFJlZnJlc2hSYXRlPjc1PC9SZWZyZXNoUmF0ZT4NCiAgICAgICAgICAgICAgICA8VmVydGljYWxSZXNvbHV0aW9uPjc2ODwvVmVydGljYWxSZXNvbHV0aW9uPg0KICAgICAgICAgICAgPC9EaXNwbGF5Pg0KICAgICAgICAgICAgPENvbXB1dGVyTmFtZT48IVtDREFUQVt3aW50ZXN0XV0+PC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGltZVpvbmU+PCFbQ0RBVEFbR01UIFN0YW5kYXJkIFRpbWVdXT48L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAgICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48IVtDREFUQVtdXT48L0RvbWFpbj4NCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwhW0NEQVRBW11dPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgIDxVc2VybmFtZT48IVtDREFUQVtdXT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlhbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PCFbQ0RBVEFbXV0+PC9Kb2luRG9tYWluPg0KICAgICAgICAgICAgICAgIDxNYWNoaW5lT2JqZWN0T1U+PCFbQ0RBVEFbXV0+PC9NYWNoaW5lT2JqZWN0T1U+DQogICAgICAgICAgICA8L0lkZW50aWZpY2F0aW9uPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxzZXR0aW5ncyBwYXNzPSJvb2JlU3lzdGVtIj4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPE9PQkU+DQogICAgICAgICAgICAgICAgPFByb3RlY3RZb3VyUEM+MjwvUHJvdGVjdFlvdXJQQz4NCiAgICAgICAgICAgICAgICA8TmV0d29ya0xvY2F0aW9uPldvcms8L05ldHdvcmtMb2NhdGlvbj4NCiAgICAgICAgICAgICAgICA8SGlkZUVVTEFQYWdlPnRydWU8L0hpZGVFVUxBUGFnZT4NCiAgICAgICAgICAgIDwvT09CRT4NCiAgICAgICAgICAgIDxVc2VyQWNjb3VudHM+DQogICAgICAgICAgICAgICAgPEFkbWluaXN0cmF0b3JQYXNzd29yZD4NCiAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgIDxQbGFpblRleHQ+dHJ1ZTwvUGxhaW5UZXh0Pg0KICAgICAgICAgICAgICAgIDwvQWRtaW5pc3RyYXRvclBhc3N3b3JkPg0KICAgICAgICAgICAgICAgIDxMb2NhbEFjY291bnRzPg0KICAgICAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50IHdjbTphY3Rpb249ImFkZCI+DQogICAgICAgICAgICAgICAgICAgICAgICA8UGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFBsYWluVGV4dD50cnVlPC9QbGFpblRleHQ+DQogICAgICAgICAgICAgICAgICAgICAgICA8L1Bhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgPEdyb3VwPmFkbWluaXN0cmF0b3JzPC9Hcm91cD4NCiAgICAgICAgICAgICAgICAgICAgICAgIDxOYW1lPnVzZXI8L05hbWU+DQogICAgICAgICAgICAgICAgICAgICAgICA8RGlzcGxheU5hbWU+dXNlcjwvRGlzcGxheU5hbWU+DQogICAgICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50Pg0KICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgIDwvVXNlckFjY291bnRzPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxjcGk6b2ZmbGluZUltYWdlIGNwaTpzb3VyY2U9IndpbTpkOi9zb3VyY2VzL2luc3RhbGwud2ltI1dpbmRvd3MgNyBFTlRFUlBSSVNFIiB4bWxuczpjcGk9InVybjpzY2hlbWFzLW1pY3Jvc29mdC1jb206Y3BpIi8+DQo8L3VuYXR0ZW5kPg0K}}},
path=, device=floppy, type=disk, readonly=true,
deviceId=c3bfcbc7-c4ca-432a-acc6-76114ca840f4}, {specParams={vram=32768,
heads=1}, device=qxl, type=video,
deviceId=c150cc76-ff34-4c8a-9770-f049f6d33590}, {shared=false,
bootOrder=2, iface=ide, index=2, specParams={},
path=/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111/en_windows_7_professional_x64_dvd_x15-65805.iso,
device=cdrom, type=disk, readonly=true,
deviceId=284ac046-c85b-46ca-aa99-0b33e734273d}, {shared=false, index=0,
volumeID=d9973802-1983-4236-9a66-1d2d1957799e, propagateErrors=off,
format=raw, type=disk, iface=virtio, bootOrder=1,
domainID=c6628e37-903e-427c-bedb-f7fe16267778,
imageID=1b105659-3f4c-4fa2-b4e3-e8fd15501eb3, specParams={},
optional=false, device=disk,
poolID=a5a001c7-bf45-444d-8e9c-884e6fedec24, readonly=false,
deviceId=1b105659-3f4c-4fa2-b4e3-e8fd15501eb3}, {bootOrder=3,
nicModel=pv, specParams={outbound={}, inbound={}},
macAddr=00:1a:4a:5d:5c:04, device=bridge, linkActive=true,
type=interface, filter=vdsm-no-mac-spoofing, network=yorku,
deviceId=d58aadde-27e2-4c76-9aa7-5f9053f0c415}, {specParams={},
device=ich6, type=sound, deviceId=05120a5d-7633-4c74-94cf-2f6ef70e1eef},
{specParams={model=virtio}, device=memballoon, type=balloon,
deviceId=cf2be11d-5f87-4c62-9877-625a90d75d3d}, {index=0,
model=virtio-scsi, specParams={}, device=scsi, type=controller,
deviceId=2c78adb0-dce6-49fc-88b8-46481d459aa2}, {specParams={},
device=virtio-serial, type=controller,
deviceId=ddee73fb-2605-4794-ab40-f4cf2ed57a1f}],vmName=wintest,cpuType=SandyBridge,fileTransferEnable=true
2015-04-16 10:49:05,288 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVmFromSysPrepVDSCommand]
(org.ovirt.thread.pool-8-thread-22)
FINISH, CreateVmFromSysPrepVDSCommand, log id: 1e76602c
2015-04-16 10:49:05,301 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(org.ovirt.thread.pool-8-thread-22) FINISH, CreateVmVDSCommand, return:
WaitForLaunch, log id: 4a30c0fc
2015-04-16 10:49:05,303 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand]
(org.ovirt.thread.pool-8-thread-22) Lock freed to object EngineLock
[exclusiveLocks= key: 454a5771-2780-4a0b-904a-e2caf08f7bdc value: VM
, sharedLocks= ]
2015-04-16 10:49:05,309 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(org.ovirt.thread.pool-8-thread-22) Correlation ID: 5704c4a, Job ID:
9049a63c-90b1-4e5f-baa5-ae507db4933d, Call Stack: null, Custom Event ID:
-1, Message: VM wintest was started by admin@internal (Host: virt3).
2015-04-16 10:49:05,403 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(DefaultQuartzScheduler_Worker-24) START, DestroyVDSCommand(HostName =
virt3, HostId = 75384239-3bb4-4627-8afc-53b8d074b56c,
vmId=454a5771-2780-4a0b-904a-e2caf08f7bdc, force=false, secondsToWait=0,
gracefully=false, reason=), log id: 7e15fcd
2015-04-16 10:49:05,412 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(DefaultQuartzScheduler_Worker-24) FINISH, DestroyVDSCommand, log id:
7e15fcd
2015-04-16 10:49:05,451 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-24) Correlation ID: null, Call Stack:
null, Custom Event ID: -1, Message: VM wintest is down with error. Exit
message: [Errno 5] could not create floppy file: code 1, out mkfs.fat
3.0.20 (12 Jun 2013)
err mkfs.msdos: unable to create
/var/run/vdsm/payload/454a5771-2780-4a0b-904a-e2caf08f7bdc.ea432ba28823b1a56eaf6b2187ed863b.img
.
2015-04-16 10:49:05,455 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-24) Running on vds during rerun failed
vm: null
2015-04-16 10:49:05,457 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-24) VM wintest
(454a5771-2780-4a0b-904a-e2caf08f7bdc) is running in db and not running
in VDS virt3
2015-04-16 10:49:05,477 ERROR
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-24) Rerun vm
454a5771-2780-4a0b-904a-e2caf08f7bdc. Called from vds virt3
2015-04-16 10:49:05,501 WARN
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(org.ovirt.thread.pool-8-thread-45) Correlation ID: 5704c4a, Job ID:
9049a63c-90b1-4e5f-baa5-ae507db4933d, Call Stack: null, Custom Event ID:
-1, Message: Failed to run VM wintest on Host virt3.
2015-04-16 10:49:05,510 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(org.ovirt.thread.pool-8-thread-45) Correlation ID: 5704c4a, Job ID:
9049a63c-90b1-4e5f-baa5-ae507db4933d, Call Stack: null, Custom Event ID:
-1, Message: Failed to run VM wintest (User: admin@internal).
2015-04-16 10:49:05,517 INFO
[org.ovirt.engine.core.bll.ProcessDownVmCommand]
(org.ovirt.thread.pool-8-thread-2) [361f1f77] Running command:
ProcessDownVmCommand internal: true.
virt1:
Thread-29717::INFO::2015-04-16
10:48:23,806::logUtils::44::dispatcher::(wrapper) Run and protect:
repoStats(options=None)
Thread-29717::INFO::2015-04-16
10:48:23,807::logUtils::47::dispatcher::(wrapper) Run and protect:
repoStats, Return response: {u'16f88659-d139-49a4-b537-7b5db9b9b1c3':
{'code': 0, 'version': 0, 'acquired': True, 'delay':
'0.000280261',
'lastCheck': '2.0', 'valid': True},
u'c6628e37-903e-427c-bedb-f7fe16267778': {'code': 0, 'version': 3,
'acquired': True, 'delay': '0.000269407', 'lastCheck':
'1.9', 'valid':
True}}
Thread-29717::DEBUG::2015-04-16
10:48:23,807::task::1191::Storage.TaskManager.Task::(prepare)
Task=`a5cfe6db-80c1-4a1c-b580-813de93c91c7`::finished:
{u'16f88659-d139-49a4-b537-7b5db9b9b1c3': {'code': 0, 'version':
0,
'acquired': True, 'delay': '0.000280261', 'lastCheck':
'2.0', 'valid':
True}, u'c6628e37-903e-427c-bedb-f7fe16267778': {'code': 0,
'version':
3, 'acquired': True, 'delay': '0.000269407', 'lastCheck':
'1.9',
'valid': True}}
Thread-29717::DEBUG::2015-04-16
10:48:23,807::task::595::Storage.TaskManager.Task::(_updateState)
Task=`a5cfe6db-80c1-4a1c-b580-813de93c91c7`::moving from state preparing
-> state finished
Thread-29717::DEBUG::2015-04-16
10:48:23,807::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll)
Owner.releaseAll requests {} resources {}
Thread-29717::DEBUG::2015-04-16
10:48:23,807::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll)
Owner.cancelAll requests {}
Thread-29717::DEBUG::2015-04-16
10:48:23,807::task::993::Storage.TaskManager.Task::(_decref)
Task=`a5cfe6db-80c1-4a1c-b580-813de93c91c7`::ref 0 aborting False
Thread-29717::ERROR::2015-04-16
10:48:23,808::API::1712::vds::(_getHaInfo) failed to retrieve Hosted
Engine HA score '[Errno 2] No such file or directory:
'/etc/ovirt-hosted-engine/hosted-engine.conf''Is the Hosted Engine setup
finished?
Thread-29717::DEBUG::2015-04-16
10:48:23,808::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:23,847::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:23,848::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29718::DEBUG::2015-04-16
10:48:23,849::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:26,934::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:26,935::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29719::DEBUG::2015-04-16
10:48:26,936::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:29,977::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:29,978::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29720::DEBUG::2015-04-16
10:48:29,979::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
Thread-1574::DEBUG::2015-04-16
10:48:31,792::fileSD::261::Storage.Misc.excCmd::(getReadDelay)
/usr/bin/dd
if=/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/dom_md/metadata
iflag=direct of=/dev/null bs=4096 count=1 (cwd None)
Thread-1574::DEBUG::2015-04-16
10:48:31,795::fileSD::261::Storage.Misc.excCmd::(getReadDelay) SUCCESS:
<err> = '0+1 records in\n0+1 records out\n318 bytes (318 B) copied,
0.000188821 s, 1.7 MB/s\n'; <rc> = 0
Thread-19::DEBUG::2015-04-16
10:48:31,867::fileSD::261::Storage.Misc.excCmd::(getReadDelay)
/usr/bin/dd
if=/rhev/data-center/mnt/virtfs-fs:_nfs_data/c6628e37-903e-427c-bedb-f7fe16267778/dom_md/metadata
iflag=direct of=/dev/null bs=4096 count=1 (cwd None)
Thread-19::DEBUG::2015-04-16
10:48:31,870::fileSD::261::Storage.Misc.excCmd::(getReadDelay) SUCCESS:
<err> = '0+1 records in\n0+1 records out\n448 bytes (448 B) copied,
0.000176593 s, 2.5 MB/s\n'; <rc> = 0
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:33,005::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:33,006::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29721::DEBUG::2015-04-16
10:48:33,007::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:36,035::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:36,036::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29722::DEBUG::2015-04-16
10:48:36,037::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:37,211::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:37,212::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29723::DEBUG::2015-04-16
10:48:37,212::__init__::469::jsonrpc.JsonRpcServer::(_serveRequest)
Calling 'VM.create' in bridge with {u'vmParams': {u'acpiEnable':
u'true', u'emulatedMachine': u'rhel6.5.0', u'vmId':
u'454a5771-2780-4a0b-904a-e2caf08f7bdc', u'memGuaranteedSize': 1365,
u'transparentHugePages': u'true', u'spiceSslCipherSuite':
u'DEFAULT',
u'cpuType': u'SandyBridge', u'smp': u'2',
u'hypervEnable': u'true',
u'custom': {}, u'vmType': u'kvm', u'memSize': 2048,
u'smpCoresPerSocket': u'1', u'vmName': u'wintest',
u'nice': u'0',
u'bootMenuEnable': u'false', u'copyPasteEnable': u'true',
u'smartcardEnable': u'false', u'fileTransferEnable':
u'true',
u'keyboardLayout': u'en-us', u'kvmEnable': u'true',
u'displayNetwork':
u'yorku', u'devices': [{u'index': u'0', u'iface':
u'fdc', u'specParams':
{u'vmPayload': {u'file': {u'"Unattend.xml"':
u'PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0idXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9IndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAgICAgICAgICAgICAgICAgICA8S2V5PjwhW0NEQVRBW11dPjwvS2V5Pg0KICAgICAgICAgICAgICAgIDwvUHJvZHVjdEtleT4NCiAgICAgICAgICAgICAgICA8QWNjZXB0RXVsYT50cnVlPC9BY2NlcHRFdWxhPg0KICAgICAgICAgICAgICAgIDxGdWxsTmFtZT4idXNlciI8L0Z1bGxOYW1lPg0KICAgICAgICAgICAgICAgIDxPcmdhbml6YXRpb24+PCFbQ0RBVEFbZWVjcy55b3JrdS5jYV1dPjwvT3JnYW5pemF0aW9uPg0KICAgICAgICAgICAgPC9Vc2VyRGF0YT4NCiAgICAgICAgICAgIDxJbWFnZUluc3RhbGw+DQogICAgICAgICAgICAgICAgPE9TSW1hZ2U+DQogICAgICAgICAgICAgICAgICAgIDxJbnN0YWxsVG9BdmFpbGFibGVQYXJ0aXRpb24+dHJ1ZTwvSW5zdGFsbFRvQXZhaWxhYmxlUGFydGl0aW9uPg0KICAgICAgICAgICAgICAgIDwvT1NJbWFnZT4NCiAgICAgICAgICAgIDwvSW1hZ2VJbnN0YWxsPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUtV2luUEUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFNldHVwVUlMYW5ndWFnZT4NCiAgICAgICAgICAgICAgICA8VUlMYW5ndWFnZT48IVtDREFUQVtlbl9VU11dPjwvVUlMYW5ndWFnZT4NCiAgICAgICAgICAgIDwvU2V0dXBVSUxhbmd1YWdlPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPjwhW0NEQVRBW2VuX1VTXV0+PC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVzZXJMb2NhbGU+PCFbQ0RBVEFbZW5fVVNdXT48L1VzZXJMb2NhbGU+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGluZ3M+DQogICAgPHNldHRpbmdzIHBhc3M9InNwZWNpYWxpemUiPg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxEaXNwbGF5Pg0KICAgICAgICAgICAgICAgIDxDb2xvckRlcHRoPjMyPC9Db2xvckRlcHRoPg0KICAgICAgICAgICAgICAgIDxEUEk+OTY8L0RQST4NCiAgICAgICAgICAgICAgICA8SG9yaXpvbnRhbFJlc29sdXRpb24+MTAyNDwvSG9yaXpvbnRhbFJlc29sdXRpb24+DQogICAgICAgICAgICAgICAgPFJlZnJlc2hSYXRlPjc1PC9SZWZyZXNoUmF0ZT4NCiAgICAgICAgICAgICAgICA8VmVydGljYWxSZXNvbHV0aW9uPjc2ODwvVmVydGljYWxSZXNvbHV0aW9uPg0KICAgICAgICAgICAgPC9EaXNwbGF5Pg0KICAgICAgICAgICAgPENvbXB1dGVyTmFtZT48IVtDREFUQVt3aW50ZXN0XV0+PC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGltZVpvbmU+PCFbQ0RBVEFbR01UIFN0YW5kYXJkIFRpbWVdXT48L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAgICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48IVtDREFUQVtdXT48L0RvbWFpbj4NCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwhW0NEQVRBW11dPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgIDxVc2VybmFtZT48IVtDREFUQVtdXT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlhbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PCFbQ0RBVEFbXV0+PC9Kb2luRG9tYWluPg0KICAgICAgICAgICAgICAgIDxNYWNoaW5lT2JqZWN0T1U+PCFbQ0RBVEFbXV0+PC9NYWNoaW5lT2JqZWN0T1U+DQogICAgICAgICAgICA8L0lkZW50aWZpY2F0aW9uPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxzZXR0aW5ncyBwYXNzPSJvb2JlU3lzdGVtIj4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPE9PQkU+DQogICAgICAgICAgICAgICAgPFByb3RlY3RZb3VyUEM+MjwvUHJvdGVjdFlvdXJQQz4NCiAgICAgICAgICAgICAgICA8TmV0d29ya0xvY2F0aW9uPldvcms8L05ldHdvcmtMb2NhdGlvbj4NCiAgICAgICAgICAgICAgICA8SGlkZUVVTEFQYWdlPnRydWU8L0hpZGVFVUxBUGFnZT4NCiAgICAgICAgICAgIDwvT09CRT4NCiAgICAgICAgICAgIDxVc2VyQWNjb3VudHM+DQogICAgICAgICAgICAgICAgPEFkbWluaXN0cmF0b3JQYXNzd29yZD4NCiAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgIDxQbGFpblRleHQ+dHJ1ZTwvUGxhaW5UZXh0Pg0KICAgICAgICAgICAgICAgIDwvQWRtaW5pc3RyYXRvclBhc3N3b3JkPg0KICAgICAgICAgICAgICAgIDxMb2NhbEFjY291bnRzPg0KICAgICAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50IHdjbTphY3Rpb249ImFkZCI+DQogICAgICAgICAgICAgICAgICAgICAgICA8UGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFBsYWluVGV4dD50cnVlPC9QbGFpblRleHQ+DQogICAgICAgICAgICAgICAgICAgICAgICA8L1Bhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgPEdyb3VwPmFkbWluaXN0cmF0b3JzPC9Hcm91cD4NCiAgICAgICAgICAgICAgICAgICAgICAgIDxOYW1lPnVzZXI8L05hbWU+DQogICAgICAgICAgICAgICAgICAgICAgICA8RGlzcGxheU5hbWU+dXNlcjwvRGlzcGxheU5hbWU+DQogICAgICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50Pg0KICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgIDwvVXNlckFjY291bnRzPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxjcGk6b2ZmbGluZUltYWdlIGNwaTpzb3VyY2U9IndpbTpkOi9zb3VyY2VzL2luc3RhbGwud2ltI1dpbmRvd3MgNyBFTlRFUlBSSVNFIiB4bWxuczpjcGk9InVybjpzY2hlbWFzLW1pY3Jvc29mdC1jb206Y3BpIi8+DQo8L3VuYXR0ZW5kPg0K'}}},
u'readonly': u'true', u'deviceId':
u'dd6f617c-2b8a-4c41-93f1-67a4617e301e', u'path': u'',
u'device':
u'floppy', u'shared': u'false', u'type': u'disk'},
{u'device': u'qxl',
u'specParams': {u'vram': u'32768', u'heads':
u'1'}, u'type': u'video',
u'deviceId': u'c150cc76-ff34-4c8a-9770-f049f6d33590'}, {u'index':
u'2',
u'iface': u'ide', u'bootOrder': u'2',
u'specParams': {}, u'readonly':
u'true', u'deviceId': u'45db0196-d16f-4820-8187-9628396e746c',
u'path':
u'/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111/en_windows_7_professional_x64_dvd_x15-65805.iso',
u'device': u'cdrom', u'shared': u'false', u'type':
u'disk'}, {u'index':
0, u'iface': u'virtio', u'format': u'raw',
u'bootOrder': u'1',
u'volumeID': u'd9973802-1983-4236-9a66-1d2d1957799e', u'imageID':
u'1b105659-3f4c-4fa2-b4e3-e8fd15501eb3', u'specParams': {},
u'readonly':
u'false', u'domainID': u'c6628e37-903e-427c-bedb-f7fe16267778',
u'optional': u'false', u'deviceId':
u'1b105659-3f4c-4fa2-b4e3-e8fd15501eb3', u'poolID':
u'a5a001c7-bf45-444d-8e9c-884e6fedec24', u'device': u'disk',
u'shared':
u'false', u'propagateErrors': u'off', u'type':
u'disk'}, {u'nicModel':
u'pv', u'macAddr': u'00:1a:4a:5d:5c:04', u'linkActive':
u'true',
u'network': u'yorku', u'bootOrder': u'3',
u'filter':
u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {},
u'outbound':
{}}, u'deviceId': u'd58aadde-27e2-4c76-9aa7-5f9053f0c415',
u'device':
u'bridge', u'type': u'interface'}, {u'device':
u'ich6', u'specParams':
{}, u'type': u'sound', u'deviceId':
u'05120a5d-7633-4c74-94cf-2f6ef70e1eef'}, {u'device':
u'memballoon',
u'specParams': {u'model': u'virtio'}, u'type':
u'balloon', u'deviceId':
u'cf2be11d-5f87-4c62-9877-625a90d75d3d'}, {u'index': u'0',
u'specParams': {}, u'deviceId':
u'2c78adb0-dce6-49fc-88b8-46481d459aa2',
u'device': u'scsi', u'model': u'virtio-scsi',
u'type': u'controller'},
{u'device': u'virtio-serial', u'specParams': {}, u'type':
u'controller',
u'deviceId': u'ddee73fb-2605-4794-ab40-f4cf2ed57a1f'}],
u'timeOffset':
u'3600', u'maxVCpus': u'16', u'spiceSecureChannels':
u'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard',
u'display':
u'qxl'}, u'vmID': u'454a5771-2780-4a0b-904a-e2caf08f7bdc'}
Thread-29723::INFO::2015-04-16
10:48:37,214::clientIF::394::vds::(createVm) vmContainerLock acquired by
vm 454a5771-2780-4a0b-904a-e2caf08f7bdc
Thread-29723::DEBUG::2015-04-16
10:48:37,221::clientIF::407::vds::(createVm) Total desktops after
creation of 454a5771-2780-4a0b-904a-e2caf08f7bdc is 1
Thread-29724::DEBUG::2015-04-16
10:48:37,221::vm::2264::vm.Vm::(_startUnderlyingVm)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::Start
Thread-29724::DEBUG::2015-04-16
10:48:37,223::vm::2268::vm.Vm::(_startUnderlyingVm)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::_ongoingCreations acquired
Thread-29724::INFO::2015-04-16 10:48:37,224::vm::3261::vm.Vm::(_run)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::VM wrapper has started
Thread-29723::DEBUG::2015-04-16
10:48:37,226::__init__::500::jsonrpc.JsonRpcServer::(_serveRequest)
Return 'VM.create' in bridge with {u'acpiEnable': u'true',
u'emulatedMachine': u'rhel6.5.0', u'vmId':
u'454a5771-2780-4a0b-904a-e2caf08f7bdc', u'memGuaranteedSize': 1365,
u'transparentHugePages': u'true', 'displaySecurePort':
'-1',
u'spiceSslCipherSuite': u'DEFAULT', u'cpuType':
u'SandyBridge', u'smp':
u'2', u'smartcardEnable': u'false', u'hypervEnable':
u'true', u'custom':
{}, u'vmType': u'kvm', u'memSize': 2048,
u'smpCoresPerSocket': u'1',
u'vmName': u'wintest', u'nice': u'0', 'status':
'WaitForLaunch',
u'bootMenuEnable': u'false', 'pid': '0',
u'copyPasteEnable': u'true',
'displayIp': '130.63.94.34', 'displayPort': '-1',
'guestDiskMapping':
{}, 'clientIp': '', u'fileTransferEnable': u'true',
'nicModel':
'rtl8139,pv', u'keyboardLayout': u'en-us', u'kvmEnable':
u'true',
u'displayNetwork': u'yorku', u'devices': [{u'index':
u'0', u'iface':
u'fdc', u'specParams': {u'vmPayload': {u'file':
{u'"Unattend.xml"':
u'PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0idXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9IndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAgICAgICAgICAgICAgICAgICA8S2V5PjwhW0NEQVRBW11dPjwvS2V5Pg0KICAgICAgICAgICAgICAgIDwvUHJvZHVjdEtleT4NCiAgICAgICAgICAgICAgICA8QWNjZXB0RXVsYT50cnVlPC9BY2NlcHRFdWxhPg0KICAgICAgICAgICAgICAgIDxGdWxsTmFtZT4idXNlciI8L0Z1bGxOYW1lPg0KICAgICAgICAgICAgICAgIDxPcmdhbml6YXRpb24+PCFbQ0RBVEFbZWVjcy55b3JrdS5jYV1dPjwvT3JnYW5pemF0aW9uPg0KICAgICAgICAgICAgPC9Vc2VyRGF0YT4NCiAgICAgICAgICAgIDxJbWFnZUluc3RhbGw+DQogICAgICAgICAgICAgICAgPE9TSW1hZ2U+DQogICAgICAgICAgICAgICAgICAgIDxJbnN0YWxsVG9BdmFpbGFibGVQYXJ0aXRpb24+dHJ1ZTwvSW5zdGFsbFRvQXZhaWxhYmxlUGFydGl0aW9uPg0KICAgICAgICAgICAgICAgIDwvT1NJbWFnZT4NCiAgICAgICAgICAgIDwvSW1hZ2VJbnN0YWxsPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUtV2luUEUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFNldHVwVUlMYW5ndWFnZT4NCiAgICAgICAgICAgICAgICA8VUlMYW5ndWFnZT48IVtDREFUQVtlbl9VU11dPjwvVUlMYW5ndWFnZT4NCiAgICAgICAgICAgIDwvU2V0dXBVSUxhbmd1YWdlPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPjwhW0NEQVRBW2VuX1VTXV0+PC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVzZXJMb2NhbGU+PCFbQ0RBVEFbZW5fVVNdXT48L1VzZXJMb2NhbGU+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGluZ3M+DQogICAgPHNldHRpbmdzIHBhc3M9InNwZWNpYWxpemUiPg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxEaXNwbGF5Pg0KICAgICAgICAgICAgICAgIDxDb2xvckRlcHRoPjMyPC9Db2xvckRlcHRoPg0KICAgICAgICAgICAgICAgIDxEUEk+OTY8L0RQST4NCiAgICAgICAgICAgICAgICA8SG9yaXpvbnRhbFJlc29sdXRpb24+MTAyNDwvSG9yaXpvbnRhbFJlc29sdXRpb24+DQogICAgICAgICAgICAgICAgPFJlZnJlc2hSYXRlPjc1PC9SZWZyZXNoUmF0ZT4NCiAgICAgICAgICAgICAgICA8VmVydGljYWxSZXNvbHV0aW9uPjc2ODwvVmVydGljYWxSZXNvbHV0aW9uPg0KICAgICAgICAgICAgPC9EaXNwbGF5Pg0KICAgICAgICAgICAgPENvbXB1dGVyTmFtZT48IVtDREFUQVt3aW50ZXN0XV0+PC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGltZVpvbmU+PCFbQ0RBVEFbR01UIFN0YW5kYXJkIFRpbWVdXT48L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAgICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48IVtDREFUQVtdXT48L0RvbWFpbj4NCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwhW0NEQVRBW11dPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgIDxVc2VybmFtZT48IVtDREFUQVtdXT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlhbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PCFbQ0RBVEFbXV0+PC9Kb2luRG9tYWluPg0KICAgICAgICAgICAgICAgIDxNYWNoaW5lT2JqZWN0T1U+PCFbQ0RBVEFbXV0+PC9NYWNoaW5lT2JqZWN0T1U+DQogICAgICAgICAgICA8L0lkZW50aWZpY2F0aW9uPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxzZXR0aW5ncyBwYXNzPSJvb2JlU3lzdGVtIj4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPjwhW0NEQVRBW2VuX1VTXV0+PC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT48IVtDREFUQVtlbl9VU11dPjwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+PCFbQ0RBVEFbZW5fVVNdXT48L1VJTGFuZ3VhZ2U+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPE9PQkU+DQogICAgICAgICAgICAgICAgPFByb3RlY3RZb3VyUEM+MjwvUHJvdGVjdFlvdXJQQz4NCiAgICAgICAgICAgICAgICA8TmV0d29ya0xvY2F0aW9uPldvcms8L05ldHdvcmtMb2NhdGlvbj4NCiAgICAgICAgICAgICAgICA8SGlkZUVVTEFQYWdlPnRydWU8L0hpZGVFVUxBUGFnZT4NCiAgICAgICAgICAgIDwvT09CRT4NCiAgICAgICAgICAgIDxVc2VyQWNjb3VudHM+DQogICAgICAgICAgICAgICAgPEFkbWluaXN0cmF0b3JQYXNzd29yZD4NCiAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgIDxQbGFpblRleHQ+dHJ1ZTwvUGxhaW5UZXh0Pg0KICAgICAgICAgICAgICAgIDwvQWRtaW5pc3RyYXRvclBhc3N3b3JkPg0KICAgICAgICAgICAgICAgIDxMb2NhbEFjY291bnRzPg0KICAgICAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50IHdjbTphY3Rpb249ImFkZCI+DQogICAgICAgICAgICAgICAgICAgICAgICA8UGFzc3dvcmQ+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFZhbHVlPjwhW0NEQVRBW2NwNHkhNV1dPjwvVmFsdWU+DQogICAgICAgICAgICAgICAgICAgICAgICAgICAgPFBsYWluVGV4dD50cnVlPC9QbGFpblRleHQ+DQogICAgICAgICAgICAgICAgICAgICAgICA8L1Bhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgPEdyb3VwPmFkbWluaXN0cmF0b3JzPC9Hcm91cD4NCiAgICAgICAgICAgICAgICAgICAgICAgIDxOYW1lPnVzZXI8L05hbWU+DQogICAgICAgICAgICAgICAgICAgICAgICA8RGlzcGxheU5hbWU+dXNlcjwvRGlzcGxheU5hbWU+DQogICAgICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50Pg0KICAgICAgICAgICAgICAgIDwvTG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgIDwvVXNlckFjY291bnRzPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICA8L3NldHRpbmdzPg0KICAgIDxjcGk6b2ZmbGluZUltYWdlIGNwaTpzb3VyY2U9IndpbTpkOi9zb3VyY2VzL2luc3RhbGwud2ltI1dpbmRvd3MgNyBFTlRFUlBSSVNFIiB4bWxuczpjcGk9InVybjpzY2hlbWFzLW1pY3Jvc29mdC1jb206Y3BpIi8+DQo8L3VuYXR0ZW5kPg0K'}}},
u'readonly': u'true', 'deviceType': u'disk',
u'deviceId':
u'dd6f617c-2b8a-4c41-93f1-67a4617e301e', u'shared': u'false',
u'device':
u'floppy', u'path': u'', u'type': u'disk'},
{u'device': u'qxl',
u'specParams': {u'vram': u'32768', u'heads':
u'1'}, 'deviceType':
u'video', u'type': u'video', u'deviceId':
u'c150cc76-ff34-4c8a-9770-f049f6d33590'}, {u'index': u'2',
u'iface':
u'ide', u'bootOrder': u'2', u'specParams': {},
u'readonly': u'true',
'deviceType': u'disk', u'deviceId':
u'45db0196-d16f-4820-8187-9628396e746c', u'shared': u'false',
u'device':
u'cdrom', u'path':
u'/rhev/data-center/mnt/virtfs-fs:_nfs_iso/16f88659-d139-49a4-b537-7b5db9b9b1c3/images/11111111-1111-1111-1111-111111111111/en_windows_7_professional_x64_dvd_x15-65805.iso',
u'type': u'disk'}, {u'index': 0, u'iface':
u'virtio', u'type': u'disk',
u'format': u'raw', u'bootOrder': u'1',
u'volumeID':
u'd9973802-1983-4236-9a66-1d2d1957799e', u'imageID':
u'1b105659-3f4c-4fa2-b4e3-e8fd15501eb3', u'specParams': {},
u'readonly':
u'false', u'domainID': u'c6628e37-903e-427c-bedb-f7fe16267778',
'deviceType': u'disk', u'deviceId':
u'1b105659-3f4c-4fa2-b4e3-e8fd15501eb3', u'poolID':
u'a5a001c7-bf45-444d-8e9c-884e6fedec24', u'device': u'disk',
u'shared':
u'false', u'propagateErrors': u'off', u'optional':
u'false'},
{u'nicModel': u'pv', u'macAddr': u'00:1a:4a:5d:5c:04',
u'linkActive':
u'true', u'network': u'yorku', u'bootOrder': u'3',
u'filter':
u'vdsm-no-mac-spoofing', u'specParams': {u'inbound': {},
u'outbound':
{}}, 'deviceType': u'interface', u'deviceId':
u'd58aadde-27e2-4c76-9aa7-5f9053f0c415', u'device': u'bridge',
u'type':
u'interface'}, {u'device': u'ich6', u'specParams': {},
'deviceType':
u'sound', u'type': u'sound', u'deviceId':
u'05120a5d-7633-4c74-94cf-2f6ef70e1eef'}, {u'device':
u'memballoon',
u'specParams': {u'model': u'virtio'}, 'deviceType':
u'balloon', u'type':
u'balloon', u'deviceId': u'cf2be11d-5f87-4c62-9877-625a90d75d3d'},
{u'index': u'0', u'specParams': {}, 'deviceType':
u'controller',
u'deviceId': u'2c78adb0-dce6-49fc-88b8-46481d459aa2', u'device':
u'scsi', u'model': u'virtio-scsi', u'type':
u'controller'}, {u'device':
u'virtio-serial', u'specParams': {}, 'deviceType':
u'controller',
u'type': u'controller', u'deviceId':
u'ddee73fb-2605-4794-ab40-f4cf2ed57a1f'}], u'timeOffset': u'3600',
u'maxVCpus': u'16', u'spiceSecureChannels':
u'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard',
u'display':
u'qxl'}
Thread-29724::DEBUG::2015-04-16
10:48:37,226::task::595::Storage.TaskManager.Task::(_updateState)
Task=`48da45b5-5a8a-4704-8568-56e8c1619570`::moving from state init ->
state preparing
Thread-29723::DEBUG::2015-04-16
10:48:37,227::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
Thread-29724::INFO::2015-04-16
10:48:37,227::logUtils::44::dispatcher::(wrapper) Run and protect:
getVolumeSize(sdUUID=u'c6628e37-903e-427c-bedb-f7fe16267778',
spUUID=u'a5a001c7-bf45-444d-8e9c-884e6fedec24',
imgUUID=u'1b105659-3f4c-4fa2-b4e3-e8fd15501eb3',
volUUID=u'd9973802-1983-4236-9a66-1d2d1957799e', options=None)
Thread-29724::INFO::2015-04-16
10:48:37,231::logUtils::47::dispatcher::(wrapper) Run and protect:
getVolumeSize, Return response: {'truesize': '0', 'apparentsize':
'42949672960'}
Thread-29724::DEBUG::2015-04-16
10:48:37,231::task::1191::Storage.TaskManager.Task::(prepare)
Task=`48da45b5-5a8a-4704-8568-56e8c1619570`::finished: {'truesize': '0',
'apparentsize': '42949672960'}
Thread-29724::DEBUG::2015-04-16
10:48:37,231::task::595::Storage.TaskManager.Task::(_updateState)
Task=`48da45b5-5a8a-4704-8568-56e8c1619570`::moving from state preparing
-> state finished
Thread-29724::DEBUG::2015-04-16
10:48:37,231::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll)
Owner.releaseAll requests {} resources {}
Thread-29724::DEBUG::2015-04-16
10:48:37,231::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll)
Owner.cancelAll requests {}
Thread-29724::DEBUG::2015-04-16
10:48:37,231::task::993::Storage.TaskManager.Task::(_decref)
Task=`48da45b5-5a8a-4704-8568-56e8c1619570`::ref 0 aborting False
Thread-29724::DEBUG::2015-04-16
10:48:37,236::vm::2294::vm.Vm::(_startUnderlyingVm)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::_ongoingCreations released
Thread-29724::ERROR::2015-04-16
10:48:37,236::vm::2331::vm.Vm::(_startUnderlyingVm)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::The vm start process failed
Traceback (most recent call last):
File "/usr/share/vdsm/virt/vm.py", line 2271, in _startUnderlyingVm
File "/usr/share/vdsm/virt/vm.py", line 3266, in _run
File "/usr/share/vdsm/virt/vm.py", line 2353, in preparePaths
File "/usr/share/vdsm/clientIF.py", line 309, in prepareVolumePath
File "/usr/share/vdsm/clientIF.py", line 353, in
_prepareVolumePathFromPayload
File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
File "<string>", line 2, in mkFloppyFs
File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in
_callmethod
OSError: [Errno 5] could not create floppy file: code 1, out mkfs.fat
3.0.20 (12 Jun 2013)
err mkfs.msdos: unable to create
/var/run/vdsm/payload/454a5771-2780-4a0b-904a-e2caf08f7bdc.ea432ba28823b1a56eaf6b2187ed863b.img
Thread-29724::DEBUG::2015-04-16
10:48:37,237::vm::2786::vm.Vm::(setDownStatus)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::Changed state to Down:
[Errno 5] could not create floppy file: code 1, out mkfs.fat 3.0.20 (12
Jun 2013)
err mkfs.msdos: unable to create
/var/run/vdsm/payload/454a5771-2780-4a0b-904a-e2caf08f7bdc.ea432ba28823b1a56eaf6b2187ed863b.img
(code=1)
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:39,061::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:39,061::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29726::DEBUG::2015-04-16
10:48:39,063::task::595::Storage.TaskManager.Task::(_updateState)
Task=`dd10f700-12ae-4519-97b5-535577d5d18b`::moving from state init ->
state preparing
Thread-29726::INFO::2015-04-16
10:48:39,064::logUtils::44::dispatcher::(wrapper) Run and protect:
repoStats(options=None)
Thread-29726::INFO::2015-04-16
10:48:39,064::logUtils::47::dispatcher::(wrapper) Run and protect:
repoStats, Return response: {u'16f88659-d139-49a4-b537-7b5db9b9b1c3':
{'code': 0, 'version': 0, 'acquired': True, 'delay':
'0.000188821',
'lastCheck': '7.3', 'valid': True},
u'c6628e37-903e-427c-bedb-f7fe16267778': {'code': 0, 'version': 3,
'acquired': True, 'delay': '0.000176593', 'lastCheck':
'7.2', 'valid':
True}}
Thread-29726::DEBUG::2015-04-16
10:48:39,064::task::1191::Storage.TaskManager.Task::(prepare)
Task=`dd10f700-12ae-4519-97b5-535577d5d18b`::finished:
{u'16f88659-d139-49a4-b537-7b5db9b9b1c3': {'code': 0, 'version':
0,
'acquired': True, 'delay': '0.000188821', 'lastCheck':
'7.3', 'valid':
True}, u'c6628e37-903e-427c-bedb-f7fe16267778': {'code': 0,
'version':
3, 'acquired': True, 'delay': '0.000176593', 'lastCheck':
'7.2',
'valid': True}}
Thread-29726::DEBUG::2015-04-16
10:48:39,064::task::595::Storage.TaskManager.Task::(_updateState)
Task=`dd10f700-12ae-4519-97b5-535577d5d18b`::moving from state preparing
-> state finished
Thread-29726::DEBUG::2015-04-16
10:48:39,064::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll)
Owner.releaseAll requests {} resources {}
Thread-29726::DEBUG::2015-04-16
10:48:39,064::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll)
Owner.cancelAll requests {}
Thread-29726::DEBUG::2015-04-16
10:48:39,064::task::993::Storage.TaskManager.Task::(_decref)
Task=`dd10f700-12ae-4519-97b5-535577d5d18b`::ref 0 aborting False
Thread-29726::ERROR::2015-04-16
10:48:39,065::API::1712::vds::(_getHaInfo) failed to retrieve Hosted
Engine HA score '[Errno 2] No such file or directory:
'/etc/ovirt-hosted-engine/hosted-engine.conf''Is the Hosted Engine setup
finished?
Thread-29726::DEBUG::2015-04-16
10:48:39,066::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:39,100::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:39,101::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29727::DEBUG::2015-04-16
10:48:39,102::stompReactor::163::yajsonrpc.StompServer::(send) Sending
response
JsonRpc (StompReactor)::DEBUG::2015-04-16
10:48:39,127::stompReactor::98::Broker.StompAdapter::(handle_frame)
Handling message <StompFrame command='SEND'>
JsonRpcServer::DEBUG::2015-04-16
10:48:39,127::__init__::506::jsonrpc.JsonRpcServer::(serve_requests)
Waiting for request
Thread-29728::DEBUG::2015-04-16
10:48:39,127::__init__::469::jsonrpc.JsonRpcServer::(_serveRequest)
Calling 'VM.destroy' in bridge with {u'vmID':
u'454a5771-2780-4a0b-904a-e2caf08f7bdc'}
Thread-29728::INFO::2015-04-16 10:48:39,128::API::332::vds::(destroy)
vmContainerLock acquired by vm 454a5771-2780-4a0b-904a-e2caf08f7bdc
Thread-29728::DEBUG::2015-04-16 10:48:39,128::vm::5026::vm.Vm::(destroy)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::destroy Called
Thread-29728::INFO::2015-04-16
10:48:39,128::vm::4952::vm.Vm::(releaseVm)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::Release VM resources
Thread-29728::WARNING::2015-04-16
10:48:39,129::vm::1953::vm.Vm::(_set_lastStatus)
vmId=`454a5771-2780-4a0b-904a-e2caf08f7bdc`::trying to set state to
Powering down when already Down
Thread-29728::WARNING::2015-04-16
10:48:39,129::utils::129::root::(rmFile) File:
/var/lib/libvirt/qemu/channels/454a5771-2780-4a0b-904a-e2caf08f7bdc.com.redhat.rhevm.vdsm
already removed
On virt1, the file exists in /var/run/vdsm/payload and is exactly
1474560 bytes. In fact, there's more than one. Now, virt1 is an ovirt
node. Is that a problem?
/var/run is tmpfs, 63 GB in size, 1% in use.
Jason.