Attachment 876365 Details for Bug 1076457 – engine log

engine log

engine.log (text/x-log), 14.05 KB, created by Lukas Svaty on 2014-03-19 14:20:00 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Lukas Svaty

Created: 2014-03-19 14:20:00 UTC

Size: 14.05 KB

patch

obsolete

>2014-03-19 15:11:55,706 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Running command: LoginUserCommand internal: false.
>2014-03-19 15:11:55,710 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-11) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin logged in.
>2014-03-19 15:12:00,934 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8702-7) [52715af2] Lock Acquired to object EngineLock [exclusiveLocks= key: e6b03061-7a3b-46f4-9fff-a786b9d501d9 value: VM
>, sharedLocks= ]
>2014-03-19 15:12:00,944 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-7) [52715af2] START, IsVmDuringInitiatingVDSCommand( vmId = e6b03061-7a3b-46f4-9fff-a786b9d501d9), log id: 769c7be0
>2014-03-19 15:12:00,944 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-7) [52715af2] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 769c7be0
>2014-03-19 15:12:00,975 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8702-7) [52715af2] Running command: RunVmCommand internal: false. Entities affected :  ID: e6b03061-7a3b-46f4-9fff-a786b9d501d9 Type: VM
>2014-03-19 15:12:01,015 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-7) [52715af2] START, CreateVmVDSCommand(HostName = red, HostId = 69542441-ee11-4c6c-92b3-93dae39ee4ff, vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, vm=VM [a]), log id: 6aef964c
>2014-03-19 15:12:01,019 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-7) [52715af2] START, CreateVDSCommand(HostName = red, HostId = 69542441-ee11-4c6c-92b3-93dae39ee4ff, vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, vm=VM [a]), log id: 690d2927
>2014-03-19 15:12:01,064 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-7) [52715af2] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.5.0,keyboardLayout=en-us,memGuaranteedSize=1024,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,maxVCpus=160,timeOffset=-18000,transparentHugePages=true,vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9,devices=[{address={bus=0x00, domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=778f48bd-1404-4af6-bc06-7b16dfd51258}, {shared=false, 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=f8630c8b-192d-4f0c-b77c-20376b8d9b88}, {shared=false, index=0, volumeID=2fbe976c-ab19-4bfb-9fce-94af66388c2e, propagateErrors=off, format=cow, type=disk, iface=virtio, bootOrder=1, address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0}, domainID=4cd91baa-e6dd-4d84-a89d-89fc458f15a9, imageID=746db1d5-a195-4dc1-833b-77c2a46f1a22, specParams={}, optional=false, device=disk, poolID=00000002-0002-0002-0002-000000000394, readonly=false, deviceId=746db1d5-a195-4dc1-833b-77c2a46f1a22}, {shared=false, volumeID=5037acb2-df07-45c2-909f-044acb0611ec, iface=virtio, imageID=efd305ae-6a25-4f32-88fe-b8930849c936, domainID=4cd91baa-e6dd-4d84-a89d-89fc458f15a9, address={bus=0x00, domain=0x0000, slot=0x08, type=pci, function=0x0}, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=00000002-0002-0002-0002-000000000394, format=raw, type=disk, readonly=false, deviceId=efd305ae-6a25-4f32-88fe-b8930849c936}, {nicModel=pv, address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0}, specParams={}, macAddr=00:1a:4a:30:54:9c, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=rhevm, deviceId=921515b8-8d80-4fb0-989b-ace2415fb852}, {specParams={model=virtio}, device=memballoon, type=balloon, deviceId=37f8db04-6037-4eeb-a924-6a0e9dceb2d9}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=46c536cf-5e66-4209-b379-b570c15b9a82}],acpiEnable=true,vmName=a,cpuType=Westmere,custom={device_2d4f6e90-aa6b-4b59-b313-8c3fa14cbe86=VmDevice {vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, deviceId=2d4f6e90-aa6b-4b59-b313-8c3fa14cbe86, 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_2d4f6e90-aa6b-4b59-b313-8c3fa14cbe86device_ce77fb27-41b9-4a4c-9659-2d226a58d7f6device_09027724-cc97-4dfa-b3b3-dfa474f449fbdevice_9e0cfb96-3240-4a93-96f2-bcd19f26c2bddevice_bf0c72ef-38a1-458c-8a3c-63d8947bfd62=VmDevice {vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, deviceId=bf0c72ef-38a1-458c-8a3c-63d8947bfd62, 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_2d4f6e90-aa6b-4b59-b313-8c3fa14cbe86device_ce77fb27-41b9-4a4c-9659-2d226a58d7f6device_09027724-cc97-4dfa-b3b3-dfa474f449fbdevice_9e0cfb96-3240-4a93-96f2-bcd19f26c2bd=VmDevice {vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, deviceId=9e0cfb96-3240-4a93-96f2-bcd19f26c2bd, 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_2d4f6e90-aa6b-4b59-b313-8c3fa14cbe86device_ce77fb27-41b9-4a4c-9659-2d226a58d7f6device_09027724-cc97-4dfa-b3b3-dfa474f449fb=VmDevice {vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, deviceId=09027724-cc97-4dfa-b3b3-dfa474f449fb, 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_2d4f6e90-aa6b-4b59-b313-8c3fa14cbe86device_ce77fb27-41b9-4a4c-9659-2d226a58d7f6=VmDevice {vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, deviceId=ce77fb27-41b9-4a4c-9659-2d226a58d7f6, 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-19 15:12:01,064 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-7) [52715af2] FINISH, CreateVDSCommand, log id: 690d2927
>2014-03-19 15:12:01,072 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-7) [52715af2] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 6aef964c
>2014-03-19 15:12:01,072 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8702-7) [52715af2] Lock freed to object EngineLock [exclusiveLocks= key: e6b03061-7a3b-46f4-9fff-a786b9d501d9 value: VM
>, sharedLocks= ]
>2014-03-19 15:12:01,077 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-7) [52715af2] Correlation ID: 52715af2, Job ID: 081aa0f8-d0b9-4f2c-b65b-389f440a70fe, Call Stack: null, Custom Event ID: -1, Message: VM a was started by admin (Host: red).
>2014-03-19 15:12:02,030 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-46) VM a e6b03061-7a3b-46f4-9fff-a786b9d501d9 moved from WaitForLaunch --> PoweringUp
>2014-03-19 15:12:26,315 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-90) VM a e6b03061-7a3b-46f4-9fff-a786b9d501d9 moved from PoweringUp --> Up
>2014-03-19 15:12:26,328 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-90) Correlation ID: 52715af2, Job ID: 081aa0f8-d0b9-4f2c-b65b-389f440a70fe, Call Stack: null, Custom Event ID: -1, Message: VM a started on Host red
>2014-03-19 15:13:10,457 INFO  [org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp-/127.0.0.1:8702-11) [6783256] Running command: SetVmTicketCommand internal: false. Entities affected :  ID: e6b03061-7a3b-46f4-9fff-a786b9d501d9 Type: VM
>2014-03-19 15:13:10,462 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp-/127.0.0.1:8702-11) [6783256] START, SetVmTicketVDSCommand(HostName = red, HostId = 69542441-ee11-4c6c-92b3-93dae39ee4ff, vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, ticket=zQSUkaJ2LNDD, validTime=120,m userName=admin, userId=fdfc627c-d875-11e0-90f0-83df133b58cc), log id: 1a92680f
>2014-03-19 15:13:10,512 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp-/127.0.0.1:8702-11) [6783256] FINISH, SetVmTicketVDSCommand, log id: 1a92680f
>2014-03-19 15:13:10,522 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-11) [6783256] Correlation ID: 6783256, Call Stack: null, Custom Event ID: -1, Message: user admin initiated console session for VM a
>2014-03-19 15:13:17,730 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin@internal is connected to VM a.
>2014-03-19 15:13:32,851 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-44) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin@internal got disconnected from VM a.
>2014-03-19 15:14:10,419 INFO  [org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp-/127.0.0.1:8702-8) [2dcc0d69] Running command: SetVmTicketCommand internal: false. Entities affected :  ID: e6b03061-7a3b-46f4-9fff-a786b9d501d9 Type: VM
>2014-03-19 15:14:10,425 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp-/127.0.0.1:8702-8) [2dcc0d69] START, SetVmTicketVDSCommand(HostName = red, HostId = 69542441-ee11-4c6c-92b3-93dae39ee4ff, vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, ticket=Y9dvLM6UFdpM, validTime=120,m userName=admin, userId=fdfc627c-d875-11e0-90f0-83df133b58cc), log id: 721500e5
>2014-03-19 15:14:10,489 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp-/127.0.0.1:8702-8) [2dcc0d69] FINISH, SetVmTicketVDSCommand, log id: 721500e5
>2014-03-19 15:14:10,499 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-8) [2dcc0d69] Correlation ID: 2dcc0d69, Call Stack: null, Custom Event ID: -1, Message: user admin initiated console session for VM a
>2014-03-19 15:14:18,230 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-65) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin@internal is connected to VM a.
>2014-03-19 15:14:59,744 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-65) Attempting to update VMs/Templates Ovf.
>2014-03-19 15:14:59,745 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-65) Attempting to update VM OVFs in Data Center Default
>2014-03-19 15:14:59,770 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (DefaultQuartzScheduler_Worker-65) START, UpdateVMVDSCommand( storagePoolId = 00000002-0002-0002-0002-000000000394, ignoreFailoverLimit = false, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 19e9654
>2014-03-19 15:14:59,869 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (DefaultQuartzScheduler_Worker-65) FINISH, UpdateVMVDSCommand, log id: 19e9654
>2014-03-19 15:14:59,872 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-65) Successfully updated VM OVFs in Data Center Default
>2014-03-19 15:14:59,872 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-65) Attempting to update template OVFs in Data Center Default
>2014-03-19 15:14:59,873 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-65) Successfully updated templates OVFs in Data Center Default
>2014-03-19 15:14:59,873 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-65) Attempting to remove unneeded template/vm OVFs in Data Center Default
>2014-03-19 15:14:59,874 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (DefaultQuartzScheduler_Worker-65) Successfully removed unneeded template/vm OVFs in Data Center Default
>2014-03-19 15:15:06,950 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (DefaultQuartzScheduler_Worker-71) Cluster: Default fail to pass HA reservation check.
>2014-03-19 15:16:04,125 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin@internal got disconnected from VM a.
>2014-03-19 15:16:05,059 INFO  [org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp-/127.0.0.1:8702-5) [4fdc4e54] Running command: SetVmTicketCommand internal: false. Entities affected :  ID: e6b03061-7a3b-46f4-9fff-a786b9d501d9 Type: VM
>2014-03-19 15:16:05,068 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp-/127.0.0.1:8702-5) [4fdc4e54] START, SetVmTicketVDSCommand(HostName = red, HostId = 69542441-ee11-4c6c-92b3-93dae39ee4ff, vmId=e6b03061-7a3b-46f4-9fff-a786b9d501d9, ticket=d2R5ZZShBrxb, validTime=120,m userName=admin, userId=fdfc627c-d875-11e0-90f0-83df133b58cc), log id: 1e8e329c
>2014-03-19 15:16:05,141 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp-/127.0.0.1:8702-5) [4fdc4e54] FINISH, SetVmTicketVDSCommand, log id: 1e8e329c
>2014-03-19 15:16:05,153 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-5) [4fdc4e54] Correlation ID: 4fdc4e54, Call Stack: null, Custom Event ID: -1, Message: user admin initiated console session for VM a
>2014-03-19 15:16:19,255 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-33) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin@internal is connected to VM a.
>

Actions: View

Attachments on bug 1076457: 876363 | 876365