2014-03-31 13:53:54,737 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-20) [787f133c] Lock Acquired to object EngineLock [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM
, sharedLocks= ]
2014-03-31 13:53:54,763 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-20) [787f133c] START, IsVmDuringInitiatingVDSCommand( vmId = 92fbccc5-1c99-4020-822b-74d43ad214c3), log id: 685abcbf
2014-03-31 13:53:54,767 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-20) [787f133c] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 685abcbf
2014-03-31 13:53:54,811 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-4) [787f133c] Running command: RunVmCommand internal: false. Entities affected :  ID: 92fbccc5-1c99-4020-822b-74d43ad214c3 Type: VM
2014-03-31 13:53:54,839 INFO  [org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit] (org.ovirt.thread.pool-6-thread-4) [787f133c] Started HA reservation scoring method
2014-03-31 13:53:54,855 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-4) [787f133c] START, CreateVmVDSCommand(HostName = virtual6, HostId = e11c37b0-d79d-417b-bd6f-6dc172f7921c, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 497b9920
2014-03-31 13:53:54,863 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-4) [787f133c] START, CreateVDSCommand(HostName = virtual6, HostId = e11c37b0-d79d-417b-bd6f-6dc172f7921c, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 7b64d5f2
2014-03-31 13:53:54,923 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-4) [787f133c] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=en-us,memGuaranteedSize=1365,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=3600,transparentHugePages=true,vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=e46018f8-fbfc-484d-891a-e7ade9f88c51}, {shared=false, bootOrder=2, iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0, type=drive}, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=8eac07a3-d353-43db-b2e3-5f8fe414bdfe}, {shared=false, index=0, volumeID=438299fb-0a72-4005-b0c8-5544eece6dba, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=041e8dbe-f405-4aca-bb78-3bf3cc7dc190, imageID=68051a25-d3de-46b4-9e4b-686d824146bf, specParams={}, optional=false, device=disk, poolID=5849b030-626e-47cb-ad90-3ce782d831b3, readonly=false, deviceId=68051a25-d3de-46b4-9e4b-686d824146bf}, {nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={outbound={peak=26880, burst=256000, average=25600}, inbound={peak=26880, burst=256000, average=25600}}, macAddr=00:1a:4a:69:90:de, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=VPO_IPPROXY, deviceId=553de5f6-e108-4c45-84d2-7a292304211f}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=68c759aa-6266-432c-9b8b-7a4b215eee57}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=fc27e446-7932-40e4-98bd-2d13237ed6f5}],acpiEnable=true,cpuShares=1024,vmName=PRTG,cpuType=Conroe,custom={device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=b6ac752a-13e2-4107-aa8e-ddc054551f15, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83e=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=83ffb451-8ba0-4b56-8082-b243b793d83e, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83edevice_f8e59a5e-20a3-4a45-91bf-804ad60f9efb=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=f8e59a5e-20a3-4a45-91bf-804ad60f9efb, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3c=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=804006d1-ed61-4c0e-9818-0d0f4f0e8e3c, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}}
2014-03-31 13:53:54,971 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-4) [787f133c] FINISH, CreateVDSCommand, log id: 7b64d5f2
2014-03-31 13:53:54,982 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-4) [787f133c] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 497b9920
2014-03-31 13:53:54,986 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-4) [787f133c] Lock freed to object EngineLock [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM
, sharedLocks= ]
2014-03-31 13:53:54,995 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-4) [787f133c] Correlation ID: 787f133c, Job ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1, Message: VM PRTG was started by admin (Host: virtual6).
2014-03-31 13:53:55,232 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-15) START, DestroyVDSCommand(HostName = virtual6, HostId = e11c37b0-d79d-417b-bd6f-6dc172f7921c, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, force=false, secondsToWait=0, gracefully=false), log id: 780a285e
2014-03-31 13:53:55,252 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-15) FINISH, DestroyVDSCommand, log id: 780a285e
2014-03-31 13:53:55,315 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-22) Domain b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini recovered from problem. vds: virtual5
2014-03-31 13:53:55,319 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-22) Domain 62f795ed-486c-443d-93e9-2c16595fe546:masini recovered from problem. vds: virtual5
2014-03-31 13:53:55,342 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-15) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM PRTG is down. Exit message: 'int' object has no attribute 'replace'.
2014-03-31 13:53:55,346 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-15) Running on vds during rerun failed vm: null
2014-03-31 13:53:55,351 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-15) VM PRTG (92fbccc5-1c99-4020-822b-74d43ad214c3) is running in db and not running in VDS virtual6
2014-03-31 13:53:55,355 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-15) add VM PRTG to HA rerun treatment
2014-03-31 13:53:55,366 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-15) Rerun vm 92fbccc5-1c99-4020-822b-74d43ad214c3. Called from vds virtual6
2014-03-31 13:53:55,375 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-25) Correlation ID: 787f133c, Job ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM PRTG on Host virtual6.
2014-03-31 13:53:55,399 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-25) Lock Acquired to object EngineLock [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM
, sharedLocks= ]
2014-03-31 13:53:55,424 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-25) START, IsVmDuringInitiatingVDSCommand( vmId = 92fbccc5-1c99-4020-822b-74d43ad214c3), log id: 80df73c
2014-03-31 13:53:55,429 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-25) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 80df73c
2014-03-31 13:53:55,458 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-25) Running command: RunVmCommand internal: false. Entities affected :  ID: 92fbccc5-1c99-4020-822b-74d43ad214c3 Type: VM
2014-03-31 13:53:55,464 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-21) Domain b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini recovered from problem. vds: virtual4
2014-03-31 13:53:55,482 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-21) Domain 62f795ed-486c-443d-93e9-2c16595fe546:masini recovered from problem. vds: virtual4
2014-03-31 13:53:55,534 INFO  [org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit] (org.ovirt.thread.pool-6-thread-25) Started HA reservation scoring method
2014-03-31 13:53:55,551 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-25) START, CreateVmVDSCommand(HostName = virtual5, HostId = ee8c0f32-5129-48ec-b024-8ae83d5835eb, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 5db07e26
2014-03-31 13:53:55,561 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-25) START, CreateVDSCommand(HostName = virtual5, HostId = ee8c0f32-5129-48ec-b024-8ae83d5835eb, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 41f8abfd
2014-03-31 13:53:55,624 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-25) org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=en-us,memGuaranteedSize=1365,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=3600,transparentHugePages=true,vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=e46018f8-fbfc-484d-891a-e7ade9f88c51}, {shared=false, bootOrder=2, iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0, type=drive}, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=8eac07a3-d353-43db-b2e3-5f8fe414bdfe}, {shared=false, index=0, volumeID=438299fb-0a72-4005-b0c8-5544eece6dba, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=041e8dbe-f405-4aca-bb78-3bf3cc7dc190, imageID=68051a25-d3de-46b4-9e4b-686d824146bf, specParams={}, optional=false, device=disk, poolID=5849b030-626e-47cb-ad90-3ce782d831b3, readonly=false, deviceId=68051a25-d3de-46b4-9e4b-686d824146bf}, {nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={outbound={peak=26880, burst=256000, average=25600}, inbound={peak=26880, burst=256000, average=25600}}, macAddr=00:1a:4a:69:90:de, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=VPO_IPPROXY, deviceId=553de5f6-e108-4c45-84d2-7a292304211f}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=68c759aa-6266-432c-9b8b-7a4b215eee57}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=fc27e446-7932-40e4-98bd-2d13237ed6f5}],acpiEnable=true,cpuShares=1024,vmName=PRTG,cpuType=Conroe,custom={device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=b6ac752a-13e2-4107-aa8e-ddc054551f15, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83e=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=83ffb451-8ba0-4b56-8082-b243b793d83e, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83edevice_f8e59a5e-20a3-4a45-91bf-804ad60f9efb=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=f8e59a5e-20a3-4a45-91bf-804ad60f9efb, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3c=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=804006d1-ed61-4c0e-9818-0d0f4f0e8e3c, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}}
2014-03-31 13:53:55,675 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-25) FINISH, CreateVDSCommand, log id: 41f8abfd
2014-03-31 13:53:55,683 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-25) FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 5db07e26
2014-03-31 13:53:55,685 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-25) Lock freed to object EngineLock [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM
, sharedLocks= ]
2014-03-31 13:53:55,692 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-25) Correlation ID: 787f133c, Job ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1, Message: VM PRTG was started by admin (Host: virtual5).
2014-03-31 13:53:58,350 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-25) START, DestroyVDSCommand(HostName = virtual5, HostId = ee8c0f32-5129-48ec-b024-8ae83d5835eb, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, force=false, secondsToWait=0, gracefully=false), log id: 49a8c278
2014-03-31 13:53:58,370 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-25) FINISH, DestroyVDSCommand, log id: 49a8c278
2014-03-31 13:53:58,433 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-36) Domain b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini recovered from problem. vds: virtual6
2014-03-31 13:53:58,436 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-36) Domain b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini has recovered from problem. No active host in the DC is reporting it as problematic, so clearing the domain recovery timer.
2014-03-31 13:53:58,445 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-36) Domain 62f795ed-486c-443d-93e9-2c16595fe546:masini recovered from problem. vds: virtual6
2014-03-31 13:53:58,450 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (org.ovirt.thread.pool-6-thread-36) Domain 62f795ed-486c-443d-93e9-2c16595fe546:masini has recovered from problem. No active host in the DC is reporting it as problematic, so clearing the domain recovery timer.
2014-03-31 13:53:58,454 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-25) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM PRTG is down. Exit message: 'int' object has no attribute 'replace'.
2014-03-31 13:53:58,460 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-25) Running on vds during rerun failed vm: null
2014-03-31 13:53:58,463 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-25) VM PRTG (92fbccc5-1c99-4020-822b-74d43ad214c3) is running in db and not running in VDS virtual5
2014-03-31 13:53:58,466 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-25) add VM PRTG to HA rerun treatment
2014-03-31 13:53:58,475 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-25) Rerun vm 92fbccc5-1c99-4020-822b-74d43ad214c3. Called from vds virtual5
2014-03-31 13:53:58,482 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-19) Correlation ID: 787f133c, Job ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM PRTG on Host virtual5.
2014-03-31 13:53:58,501 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-19) Lock Acquired to object EngineLock [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM
, sharedLocks= ]
2014-03-31 13:53:58,526 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-19) START, IsVmDuringInitiatingVDSCommand( vmId = 92fbccc5-1c99-4020-822b-74d43ad214c3), log id: 2b5bee5b
2014-03-31 13:53:58,532 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-6-thread-19) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 2b5bee5b
2014-03-31 13:53:58,556 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-19) Running command: RunVmCommand internal: false. Entities affected :  ID: 92fbccc5-1c99-4020-822b-74d43ad214c3 Type: VM
2014-03-31 13:53:58,589 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-19) START, CreateVmVDSCommand(HostName = virtual4, HostId = f4fc34ed-4261-46f1-ac5e-c849eb7e9b7a, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 5bfb709
2014-03-31 13:53:58,596 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-19) START, CreateVDSCommand(HostName = virtual4, HostId = f4fc34ed-4261-46f1-ac5e-c849eb7e9b7a, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 2fa9cbb3
2014-03-31 13:53:58,887 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-19) org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=en-us,memGuaranteedSize=1365,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=3600,transparentHugePages=true,vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=e46018f8-fbfc-484d-891a-e7ade9f88c51}, {shared=false, bootOrder=2, iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0, type=drive}, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=8eac07a3-d353-43db-b2e3-5f8fe414bdfe}, {shared=false, index=0, volumeID=438299fb-0a72-4005-b0c8-5544eece6dba, propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=041e8dbe-f405-4aca-bb78-3bf3cc7dc190, imageID=68051a25-d3de-46b4-9e4b-686d824146bf, specParams={}, optional=false, device=disk, poolID=5849b030-626e-47cb-ad90-3ce782d831b3, readonly=false, deviceId=68051a25-d3de-46b4-9e4b-686d824146bf}, {nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={outbound={peak=26880, burst=256000, average=25600}, inbound={peak=26880, burst=256000, average=25600}}, macAddr=00:1a:4a:69:90:de, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=VPO_IPPROXY, deviceId=553de5f6-e108-4c45-84d2-7a292304211f}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=68c759aa-6266-432c-9b8b-7a4b215eee57}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=fc27e446-7932-40e4-98bd-2d13237ed6f5}],acpiEnable=true,cpuShares=1024,vmName=PRTG,cpuType=Conroe,custom={device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=b6ac752a-13e2-4107-aa8e-ddc054551f15, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83e=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=83ffb451-8ba0-4b56-8082-b243b793d83e, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83edevice_f8e59a5e-20a3-4a45-91bf-804ad60f9efb=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=f8e59a5e-20a3-4a45-91bf-804ad60f9efb, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}, device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3c=VmDevice {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, deviceId=804006d1-ed61-4c0e-9818-0d0f4f0e8e3c, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}}
2014-03-31 13:53:58,936 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-6-thread-19) FINISH, CreateVDSCommand, log id: 2fa9cbb3
2014-03-31 13:53:58,943 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-6-thread-19) FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 5bfb709
2014-03-31 13:53:58,946 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-6-thread-19) Lock freed to object EngineLock [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM
, sharedLocks= ]
2014-03-31 13:53:58,952 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-19) Correlation ID: 787f133c, Job ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1, Message: VM PRTG was started by admin (Host: virtual4).
2014-03-31 13:53:59,142 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-26) START, GlusterVolumesListVDSCommand(HostName = virtual6, HostId = e11c37b0-d79d-417b-bd6f-6dc172f7921c), log id: 45331b03
2014-03-31 13:53:59,186 INFO  [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler_Worker-26) FINISH, GlusterVolumesListVDSCommand, return: {3eb2a0de-c6f7-4ef8-a9fd-777d9e529adc=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@7ab347f8, a297bf18-e147-4792-b590-1f485732f8ee=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@237e1375}, log id: 45331b03
2014-03-31 13:54:01,519 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-32) START, DestroyVDSCommand(HostName = virtual4, HostId = f4fc34ed-4261-46f1-ac5e-c849eb7e9b7a, vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, force=false, secondsToWait=0, gracefully=false), log id: 27d3bc97
2014-03-31 13:54:01,540 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-32) FINISH, DestroyVDSCommand, log id: 27d3bc97
2014-03-31 13:54:01,561 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-32) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM PRTG is down. Exit message: 'int' object has no attribute 'replace'.
2014-03-31 13:54:01,565 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-32) Running on vds during rerun failed vm: null
2014-03-31 13:54:01,568 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-32) VM PRTG (92fbccc5-1c99-4020-822b-74d43ad214c3) is running in db and not running in VDS virtual4
2014-03-31 13:54:01,571 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-32) add VM PRTG to HA rerun treatment
2014-03-31 13:54:01,579 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-32) Rerun vm 92fbccc5-1c99-4020-822b-74d43ad214c3. Called from vds virtual4
2014-03-31 13:54:01,587 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-14) Correlation ID: 787f133c, Job ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM PRTG on Host virtual4.
2014-03-31 13:54:01,605 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-14) Correlation ID: 787f133c, Job ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM PRTG (User: admin).