Attachment 833111 Details for Bug 1008146 – engine log

engine log

2hostsRAMfilter.log (text/x-log), 27.90 KB, created by Lukas Svaty on 2013-12-05 11:50:26 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Lukas Svaty

Created: 2013-12-05 11:50:26 UTC

Size: 27.90 KB

patch

obsolete

>2013-12-05 12:47:20,170 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-48) [6f2048fe] Start running CanDoAction for command number 1/2 (Command type: RunVm)
>2013-12-05 12:47:20,171 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-49) [176750ea] Lock Acquired to object EngineLock [exclusiveLocks= key: c5f34b47-8c31-4ce5-bc27-dc7d45590c45 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:20,171 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [6f2048fe] Lock Acquired to object EngineLock [exclusiveLocks= key: e17fddca-1ed8-4fc2-9408-a77b1750da48 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:20,177 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-49) [176750ea] START, IsVmDuringInitiatingVDSCommand( vmId = c5f34b47-8c31-4ce5-bc27-dc7d45590c45), log id: 58a98897
>2013-12-05 12:47:20,177 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-49) [176750ea] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 58a98897
>2013-12-05 12:47:20,183 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-49) [176750ea] Finish handling CanDoAction for command number 2/2 (Command type: RunVm)
>2013-12-05 12:47:20,188 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-48) [6f2048fe] START, IsVmDuringInitiatingVDSCommand( vmId = e17fddca-1ed8-4fc2-9408-a77b1750da48), log id: 33ef7afa
>2013-12-05 12:47:20,188 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-48) [6f2048fe] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 33ef7afa
>2013-12-05 12:47:20,193 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-48) [6f2048fe] Finish handling CanDoAction for command number 1/2 (Command type: RunVm)
>2013-12-05 12:47:20,234 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [176750ea] Running command: RunVmCommand internal: false. Entities affected :  ID: c5f34b47-8c31-4ce5-bc27-dc7d45590c45 Type: VM
>2013-12-05 12:47:20,256 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-48) [176750ea] START, IsoPrefixVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, storagePoolId=0e9df2d4-d3f6-426a-abcf-6ceda57e827f), log id: 2b076005
>2013-12-05 12:47:20,269 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-48) [176750ea] FINISH, IsoPrefixVDSCommand, return: , log id: 2b076005
>2013-12-05 12:47:20,270 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-48) [176750ea] START, CreateVmVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, vm=VM [a]), log id: 6b43f70f
>2013-12-05 12:47:20,273 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [176750ea] START, CreateVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, vm=VM [a]), log id: 762c2de2
>2013-12-05 12:47:20,321 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [176750ea] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=7168,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=7168,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=0,transparentHugePages=true,vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45,devices=[Ljava.util.HashMap;@2dcb2265,acpiEnable=true,vmName=a,cpuType=SandyBridge,custom={device_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4device_727fabc1-7da5-4e11-a2cc-951cfb42da4cdevice_43d014a1-bbf8-4086-8f2f-813227b7fb0d=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=43d014a1-bbf8-4086-8f2f-813227b7fb0d, 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_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4device_727fabc1-7da5-4e11-a2cc-951cfb42da4c=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=727fabc1-7da5-4e11-a2cc-951cfb42da4c, 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_7590fd9c-02b6-4cd9-804c-c520f5826175=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=7590fd9c-02b6-4cd9-804c-c520f5826175, 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_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=d594b959-0b8d-48bd-b890-0300b9204fd4, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}, device_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4device_727fabc1-7da5-4e11-a2cc-951cfb42da4cdevice_43d014a1-bbf8-4086-8f2f-813227b7fb0ddevice_76394edc-543b-417a-b86d-0f82dfae85eb=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=76394edc-543b-417a-b86d-0f82dfae85eb, 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}}
>2013-12-05 12:47:20,321 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [176750ea] FINISH, CreateVDSCommand, log id: 762c2de2
>2013-12-05 12:47:20,334 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-48) [176750ea] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 6b43f70f
>2013-12-05 12:47:20,335 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [176750ea] Lock freed to object EngineLock [exclusiveLocks= key: c5f34b47-8c31-4ce5-bc27-dc7d45590c45 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:20,343 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-48) [176750ea] Correlation ID: 176750ea, Job ID: d7e2c268-27f9-4b0b-a098-fcbb19b1197d, Call Stack: null, Custom Event ID: -1, Message: VM a was started by admin@internal (Host: 10.34.62.204).
>2013-12-05 12:47:20,384 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [6f2048fe] Running command: RunVmCommand internal: false. Entities affected :  ID: e17fddca-1ed8-4fc2-9408-a77b1750da48 Type: VM
>2013-12-05 12:47:20,408 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-48) [6f2048fe] START, IsoPrefixVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, storagePoolId=0e9df2d4-d3f6-426a-abcf-6ceda57e827f), log id: 32bf52f3
>2013-12-05 12:47:20,418 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-48) [6f2048fe] FINISH, IsoPrefixVDSCommand, return: , log id: 32bf52f3
>2013-12-05 12:47:20,420 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-48) [6f2048fe] START, CreateVmVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, vm=VM [b]), log id: 11002e76
>2013-12-05 12:47:20,423 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [6f2048fe] START, CreateVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, vm=VM [b]), log id: 4b43c57d
>2013-12-05 12:47:20,457 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [6f2048fe] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=7168,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=7168,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=0,transparentHugePages=true,vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48,devices=[Ljava.util.HashMap;@194bfb0e,acpiEnable=true,vmName=b,cpuType=SandyBridge,custom={device_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08device_eba2f8ba-1193-4f49-b4b0-826986cb7d48=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=eba2f8ba-1193-4f49-b4b0-826986cb7d48, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08device_eba2f8ba-1193-4f49-b4b0-826986cb7d48device_a3ddb4eb-ded6-4f36-96c5-53ca3c0a222a=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=a3ddb4eb-ded6-4f36-96c5-53ca3c0a222a, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=716d061f-aeb7-48ad-b7a7-f3daefd7ad18, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08device_eba2f8ba-1193-4f49-b4b0-826986cb7d48device_a3ddb4eb-ded6-4f36-96c5-53ca3c0a222adevice_4548fbd5-df30-4128-be9d-65cc420207e2=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=4548fbd5-df30-4128-be9d-65cc420207e2, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=a0e5c1a1-f88f-4af1-b85b-39c6afe0da08, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}}
>2013-12-05 12:47:20,458 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [6f2048fe] FINISH, CreateVDSCommand, log id: 4b43c57d
>2013-12-05 12:47:20,476 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-48) [6f2048fe] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 11002e76
>2013-12-05 12:47:20,476 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [6f2048fe] Lock freed to object EngineLock [exclusiveLocks= key: e17fddca-1ed8-4fc2-9408-a77b1750da48 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:20,484 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-48) [6f2048fe] Correlation ID: 6f2048fe, Job ID: 43e9e54f-5e97-40cc-a668-737148fdbd9f, Call Stack: null, Custom Event ID: -1, Message: VM b was started by admin@internal (Host: 10.34.62.204).
>2013-12-05 12:47:23,177 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-45) [13c6b875] START, DestroyVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, force=false, secondsToWait=0, gracefully=false), log id: 60e02f1c
>2013-12-05 12:47:23,196 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-45) [13c6b875] FINISH, DestroyVDSCommand, log id: 60e02f1c
>2013-12-05 12:47:23,281 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-45) [13c6b875] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM b is down. Exit message: internal error process exited while connecting to monitor: Cannot set up guest memory 'pc.ram': Cannot allocate memory
>.
>2013-12-05 12:47:23,282 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-45) [13c6b875] Running on vds during rerun failed vm: null
>2013-12-05 12:47:23,284 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-45) [13c6b875] START, DestroyVDSCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, force=false, secondsToWait=0, gracefully=false), log id: 2714306d
>2013-12-05 12:47:23,302 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-45) [13c6b875] FINISH, DestroyVDSCommand, log id: 2714306d
>2013-12-05 12:47:23,340 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-45) [13c6b875] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM a is down. Exit message: internal error process exited while connecting to monitor: Cannot set up guest memory 'pc.ram': Cannot allocate memory
>.
>2013-12-05 12:47:23,340 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-45) [13c6b875] Running on vds during rerun failed vm: null
>2013-12-05 12:47:23,342 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-45) [13c6b875] vm b running in db and not running in vds - add to rerun treatment. vds 10.34.62.204
>2013-12-05 12:47:23,342 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-45) [13c6b875] vm a running in db and not running in vds - add to rerun treatment. vds 10.34.62.204
>2013-12-05 12:47:23,342 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-45) [13c6b875] START, FullListVdsCommand(HostName = 10.34.62.204, HostId = da89bbfe-c912-4980-affe-78b07fcdcbe8, vds=Host[10.34.62.204], vmIds=[e17fddca-1ed8-4fc2-9408-a77b1750da48, c5f34b47-8c31-4ce5-bc27-dc7d45590c45]), log id: 595841f0
>2013-12-05 12:47:23,347 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-45) [13c6b875] FINISH, FullListVdsCommand, return: [Ljava.util.HashMap;@456703e0, log id: 595841f0
>2013-12-05 12:47:23,391 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-45) [13c6b875] Rerun vm e17fddca-1ed8-4fc2-9408-a77b1750da48. Called from vds 10.34.62.204
>2013-12-05 12:47:23,391 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-45) [13c6b875] Rerun vm c5f34b47-8c31-4ce5-bc27-dc7d45590c45. Called from vds 10.34.62.204
>2013-12-05 12:47:23,415 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-48) [13c6b875] Correlation ID: 6f2048fe, Job ID: 43e9e54f-5e97-40cc-a668-737148fdbd9f, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM b on Host 10.34.62.204.
>2013-12-05 12:47:23,423 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-49) [13c6b875] Correlation ID: 176750ea, Job ID: d7e2c268-27f9-4b0b-a098-fcbb19b1197d, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM a on Host 10.34.62.204.
>2013-12-05 12:47:23,456 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-49) [13c6b875] Lock Acquired to object EngineLock [exclusiveLocks= key: c5f34b47-8c31-4ce5-bc27-dc7d45590c45 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:23,474 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [13c6b875] Lock Acquired to object EngineLock [exclusiveLocks= key: e17fddca-1ed8-4fc2-9408-a77b1750da48 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:23,489 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-49) [13c6b875] START, IsVmDuringInitiatingVDSCommand( vmId = c5f34b47-8c31-4ce5-bc27-dc7d45590c45), log id: eee1e6a
>2013-12-05 12:47:23,489 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-49) [13c6b875] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: eee1e6a
>2013-12-05 12:47:23,506 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-48) [13c6b875] START, IsVmDuringInitiatingVDSCommand( vmId = e17fddca-1ed8-4fc2-9408-a77b1750da48), log id: 5f54fc0
>2013-12-05 12:47:23,506 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-48) [13c6b875] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 5f54fc0
>2013-12-05 12:47:23,540 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [13c6b875] Running command: RunVmCommand internal: false. Entities affected :  ID: e17fddca-1ed8-4fc2-9408-a77b1750da48 Type: VM
>2013-12-05 12:47:23,540 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-49) [13c6b875] Running command: RunVmCommand internal: false. Entities affected :  ID: c5f34b47-8c31-4ce5-bc27-dc7d45590c45 Type: VM
>2013-12-05 12:47:23,644 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-48) [13c6b875] START, IsoPrefixVDSCommand(HostName = 10.34.62.203, HostId = 61622138-d8cc-4694-bf1a-2fa0328e8189, storagePoolId=0e9df2d4-d3f6-426a-abcf-6ceda57e827f), log id: 53816418
>2013-12-05 12:47:23,661 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-48) [13c6b875] FINISH, IsoPrefixVDSCommand, return: , log id: 53816418
>2013-12-05 12:47:23,670 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-49) [13c6b875] START, IsoPrefixVDSCommand(HostName = 10.34.62.203, HostId = 61622138-d8cc-4694-bf1a-2fa0328e8189, storagePoolId=0e9df2d4-d3f6-426a-abcf-6ceda57e827f), log id: 5ddf4f0
>2013-12-05 12:47:23,672 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-48) [13c6b875] START, CreateVmVDSCommand(HostName = 10.34.62.203, HostId = 61622138-d8cc-4694-bf1a-2fa0328e8189, vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, vm=VM [b]), log id: 646ba049
>2013-12-05 12:47:23,675 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [13c6b875] START, CreateVDSCommand(HostName = 10.34.62.203, HostId = 61622138-d8cc-4694-bf1a-2fa0328e8189, vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, vm=VM [b]), log id: 23ef78e2
>2013-12-05 12:47:23,691 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-4-thread-49) [13c6b875] FINISH, IsoPrefixVDSCommand, return: , log id: 5ddf4f0
>2013-12-05 12:47:23,716 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-49) [13c6b875] START, CreateVmVDSCommand(HostName = 10.34.62.203, HostId = 61622138-d8cc-4694-bf1a-2fa0328e8189, vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, vm=VM [a]), log id: 4a205e72
>2013-12-05 12:47:23,731 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [13c6b875] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=7168,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=7168,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=0,transparentHugePages=true,vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48,devices=[Ljava.util.HashMap;@6a565a50,acpiEnable=true,vmName=b,cpuType=SandyBridge,custom={device_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08device_eba2f8ba-1193-4f49-b4b0-826986cb7d48=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=eba2f8ba-1193-4f49-b4b0-826986cb7d48, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08device_eba2f8ba-1193-4f49-b4b0-826986cb7d48device_a3ddb4eb-ded6-4f36-96c5-53ca3c0a222a=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=a3ddb4eb-ded6-4f36-96c5-53ca3c0a222a, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=716d061f-aeb7-48ad-b7a7-f3daefd7ad18, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08device_eba2f8ba-1193-4f49-b4b0-826986cb7d48device_a3ddb4eb-ded6-4f36-96c5-53ca3c0a222adevice_4548fbd5-df30-4128-be9d-65cc420207e2=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=4548fbd5-df30-4128-be9d-65cc420207e2, 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_716d061f-aeb7-48ad-b7a7-f3daefd7ad18device_a0e5c1a1-f88f-4af1-b85b-39c6afe0da08=VmDevice {vmId=e17fddca-1ed8-4fc2-9408-a77b1750da48, deviceId=a0e5c1a1-f88f-4af1-b85b-39c6afe0da08, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}}
>2013-12-05 12:47:23,732 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-48) [13c6b875] FINISH, CreateVDSCommand, log id: 23ef78e2
>2013-12-05 12:47:23,748 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-48) [13c6b875] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 646ba049
>2013-12-05 12:47:23,748 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-48) [13c6b875] Lock freed to object EngineLock [exclusiveLocks= key: e17fddca-1ed8-4fc2-9408-a77b1750da48 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:23,752 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-49) [13c6b875] START, CreateVDSCommand(HostName = 10.34.62.203, HostId = 61622138-d8cc-4694-bf1a-2fa0328e8189, vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, vm=VM [a]), log id: ffd8b1
>2013-12-05 12:47:23,778 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-48) [13c6b875] Correlation ID: 6f2048fe, Job ID: 43e9e54f-5e97-40cc-a668-737148fdbd9f, Call Stack: null, Custom Event ID: -1, Message: VM b was started by admin@internal (Host: 10.34.62.203).
>2013-12-05 12:47:23,787 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-49) [13c6b875] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=7168,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=7168,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=0,transparentHugePages=true,vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45,devices=[Ljava.util.HashMap;@26268bf8,acpiEnable=true,vmName=a,cpuType=SandyBridge,custom={device_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4device_727fabc1-7da5-4e11-a2cc-951cfb42da4cdevice_43d014a1-bbf8-4086-8f2f-813227b7fb0d=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=43d014a1-bbf8-4086-8f2f-813227b7fb0d, 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_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4device_727fabc1-7da5-4e11-a2cc-951cfb42da4c=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=727fabc1-7da5-4e11-a2cc-951cfb42da4c, 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_7590fd9c-02b6-4cd9-804c-c520f5826175=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=7590fd9c-02b6-4cd9-804c-c520f5826175, 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_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=d594b959-0b8d-48bd-b890-0300b9204fd4, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}, device_7590fd9c-02b6-4cd9-804c-c520f5826175device_d594b959-0b8d-48bd-b890-0300b9204fd4device_727fabc1-7da5-4e11-a2cc-951cfb42da4cdevice_43d014a1-bbf8-4086-8f2f-813227b7fb0ddevice_76394edc-543b-417a-b86d-0f82dfae85eb=VmDevice {vmId=c5f34b47-8c31-4ce5-bc27-dc7d45590c45, deviceId=76394edc-543b-417a-b86d-0f82dfae85eb, 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}}
>2013-12-05 12:47:23,787 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-49) [13c6b875] FINISH, CreateVDSCommand, log id: ffd8b1
>2013-12-05 12:47:23,806 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-49) [13c6b875] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 4a205e72
>2013-12-05 12:47:23,806 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-49) [13c6b875] Lock freed to object EngineLock [exclusiveLocks= key: c5f34b47-8c31-4ce5-bc27-dc7d45590c45 value: VM
>, sharedLocks= ]
>2013-12-05 12:47:23,833 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-49) [13c6b875] Correlation ID: 176750ea, Job ID: d7e2c268-27f9-4b0b-a098-fcbb19b1197d, Call Stack: null, Custom Event ID: -1, Message: VM a was started by admin@internal (Host: 10.34.62.203).
>2013-12-05 12:47:25,918 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-57) VM b e17fddca-1ed8-4fc2-9408-a77b1750da48 moved from WaitForLaunch --> PoweringUp
>2013-12-05 12:47:25,918 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-57) VM a c5f34b47-8c31-4ce5-bc27-dc7d45590c45 moved from WaitForLaunch --> PoweringUp
>2013-12-05 12:47:25,918 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-57) START, FullListVdsCommand(HostName = 10.34.62.203, HostId = 61622138-d8cc-4694-bf1a-2fa0328e8189, vds=Host[10.34.62.203], vmIds=[e17fddca-1ed8-4fc2-9408-a77b1750da48, c5f34b47-8c31-4ce5-bc27-dc7d45590c45]), log id: 642c081a
>2013-12-05 12:47:25,931 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (DefaultQuartzScheduler_Worker-57) FINISH, FullListVdsCommand, return: [Ljava.util.HashMap;@69558f6f, log id: 642c081a
>2013-12-05 12:48:24,041 INFO  [org.ovi

Actions: View

Attachments on bug 1008146: 797822 | 798263 | 833111