Attachment 604527 Details for Bug 848266 – engine log while trying to start up VM on vdsm-28

engine log while trying to start up VM on vdsm-28

engine.log (text/plain), 20.85 KB, created by Chao Yang on 2012-08-15 07:37:50 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chao Yang

Created: 2012-08-15 07:37:50 UTC

Size: 20.85 KB

patch

obsolete

>2012-08-15 15:34:08,090 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-/0.0.0.0:8443-6) START, IsValidVDSCommand(storagePoolId = a9814774-ab07-46a9-abf8-e42a2fbf8f33, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 55102ac3
>2012-08-15 15:34:08,091 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http-/0.0.0.0:8443-6) FINISH, IsValidVDSCommand, return: true, log id: 55102ac3
>2012-08-15 15:34:08,105 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http-/0.0.0.0:8443-6) START, IsVmDuringInitiatingVDSCommand(vmId = 2445ad59-8c84-4315-9119-3f2f4820b946), log id: 2ff5effb
>2012-08-15 15:34:08,105 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (http-/0.0.0.0:8443-6) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 2ff5effb
>2012-08-15 15:34:08,107 INFO  [org.ovirt.engine.core.bll.VdsSelector] (http-/0.0.0.0:8443-6) Checking for a specific VDS only - id:98b8ff54-e519-11e1-9434-0f4490f84999, name:intel-e5530-8-2.englab.nay.redhat.com, host_name(ip):10.66.104.53
>2012-08-15 15:34:08,130 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-42) [25af9d8f] Lock Acquired to object EngineLock [exclusiveLocks= key: 2445ad59-8c84-4315-9119-3f2f4820b946 value: VM
>, sharedLocks= ]
>2012-08-15 15:34:08,147 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-42) [25af9d8f] Running command: RunVmCommand internal: false. Entities affected :  ID: 2445ad59-8c84-4315-9119-3f2f4820b946 Type: VM
>2012-08-15 15:34:08,151 INFO  [org.ovirt.engine.core.bll.VdsSelector] (pool-4-thread-42) [25af9d8f] Checking for a specific VDS only - id:98b8ff54-e519-11e1-9434-0f4490f84999, name:intel-e5530-8-2.englab.nay.redhat.com, host_name(ip):10.66.104.53
>2012-08-15 15:34:08,164 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-42) [25af9d8f] START, CreateVmVDSCommand(vdsId = 98b8ff54-e519-11e1-9434-0f4490f84999, vmId=2445ad59-8c84-4315-9119-3f2f4820b946, vm=org.ovirt.engine.core.common.businessentities.VM@1afb1d77), log id: 26cfe5d9
>2012-08-15 15:34:09,595 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-42) [25af9d8f] START, CreateVDSCommand(vdsId = 98b8ff54-e519-11e1-9434-0f4490f84999, vmId=2445ad59-8c84-4315-9119-3f2f4820b946, vm=org.ovirt.engine.core.common.businessentities.VM@1afb1d77), log id: af9c8f4
>2012-08-15 15:34:19,653 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-42) [25af9d8f] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=512,kvmEnable=true,smp=1,emulatedMachine=rhel6.3.0,vmType=kvm,keyboardLayout=en-us,pitReinjection=false,nice=0,display=qxl,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,timeOffset=0,transparentHugePages=true,vmId=2445ad59-8c84-4315-9119-3f2f4820b946,devices=[Ljava.util.Map;@3dd2734e,acpiEnable=true,vmName=rpro,cpuType=Conroe,custom={device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=ecbbe624-f409-4b67-ad54-3e5b6e7eef73,Device=virtio-serial,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=virtio-serial0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=cc02340a-9bf9-42ac-a4aa-91a603849a43,Device=ide,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=ide0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73device_0f6eb766-3a1d-43a6-b487-a861053407f5device_68dc2a6e-7731-47b7-b677-5b3cf7bc95addevice_1b419824-7579-4aa4-a0e2-01650399bf8d=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=1b419824-7579-4aa4-a0e2-01650399bf8d,Device=usb,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x2},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=usb0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73device_0f6eb766-3a1d-43a6-b487-a861053407f5=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=0f6eb766-3a1d-43a6-b487-a861053407f5,Device=unix,Type=channel,BootOrder=0,SpecParams={},Address={port=1, bus=0, controller=0, type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73device_0f6eb766-3a1d-43a6-b487-a861053407f5device_68dc2a6e-7731-47b7-b677-5b3cf7bc95ad=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=68dc2a6e-7731-47b7-b677-5b3cf7bc95ad,Device=spicevmc,Type=channel,BootOrder=0,SpecParams={},Address={port=2, bus=0, controller=0, type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel1}
>2012-08-15 15:34:19,653 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-42) [25af9d8f] FINISH, CreateVDSCommand, log id: af9c8f4
>2012-08-15 15:34:19,654 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-42) [25af9d8f] IncreasePendingVms::CreateVmIncreasing vds intel-e5530-8-2.englab.nay.redhat.com pending vcpu count, now 1. Vm: rpro
>2012-08-15 15:34:19,685 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-42) [25af9d8f] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 26cfe5d9
>2012-08-15 15:34:19,696 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-42) [25af9d8f] Lock freed to object EngineLock [exclusiveLocks= key: 2445ad59-8c84-4315-9119-3f2f4820b946 value: VM
>, sharedLocks= ]
>2012-08-15 15:34:32,208 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-77) [257e9a] XML RPC error in command GetCapabilitiesVDS ( Vds: intel-5310-32-1.englab.nay.redhat.com ), the error was: java.util.concurrent.TimeoutException, TimeoutException:  
>2012-08-15 15:34:39,612 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-33) [2f02a0e6] START, DestroyVDSCommand(vdsId = 98b8ff54-e519-11e1-9434-0f4490f84999, vmId=2445ad59-8c84-4315-9119-3f2f4820b946, force=false, secondsToWait=0, gracefully=false), log id: 93b2e51
>2012-08-15 15:34:49,702 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-33) [2f02a0e6] FINISH, DestroyVDSCommand, log id: 93b2e51
>2012-08-15 15:34:49,736 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-33) [2f02a0e6] Running on vds during rerun failed vm: null
>2012-08-15 15:34:49,737 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-33) [2f02a0e6] vm rpro running in db and not running in vds - add to rerun treatment. vds intel-e5530-8-2.englab.nay.redhat.com
>2012-08-15 15:34:49,778 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-33) [2f02a0e6] Rerun vm 2445ad59-8c84-4315-9119-3f2f4820b946. Called from vds intel-e5530-8-2.englab.nay.redhat.com
>2012-08-15 15:34:49,787 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (pool-4-thread-44) [2f02a0e6] START, UpdateVdsDynamicDataVDSCommand(vdsId = 98b8ff54-e519-11e1-9434-0f4490f84999, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@39b4816e), log id: c959682
>2012-08-15 15:34:49,795 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (pool-4-thread-44) [2f02a0e6] FINISH, UpdateVdsDynamicDataVDSCommand, log id: c959682
>2012-08-15 15:34:49,811 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-44) [2f02a0e6] Lock Acquired to object EngineLock [exclusiveLocks= key: 2445ad59-8c84-4315-9119-3f2f4820b946 value: VM
>, sharedLocks= ]
>2012-08-15 15:34:49,818 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-44) [2f02a0e6] START, IsValidVDSCommand(storagePoolId = a9814774-ab07-46a9-abf8-e42a2fbf8f33, ignoreFailoverLimit = false, compatabilityVersion = null), log id: eab0724
>2012-08-15 15:34:49,819 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-44) [2f02a0e6] FINISH, IsValidVDSCommand, return: true, log id: eab0724
>2012-08-15 15:34:49,837 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-44) [2f02a0e6] START, IsVmDuringInitiatingVDSCommand(vmId = 2445ad59-8c84-4315-9119-3f2f4820b946), log id: 72538216
>2012-08-15 15:34:49,837 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-44) [2f02a0e6] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 72538216
>2012-08-15 15:34:49,838 INFO  [org.ovirt.engine.core.bll.VdsSelector] (pool-4-thread-44) [2f02a0e6] Checking for a specific VDS only - id:98b8ff54-e519-11e1-9434-0f4490f84999, name:intel-e5530-8-2.englab.nay.redhat.com, host_name(ip):10.66.104.53
>2012-08-15 15:34:49,861 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-44) [2f02a0e6] Running command: RunVmCommand internal: false. Entities affected :  ID: 2445ad59-8c84-4315-9119-3f2f4820b946 Type: VM
>2012-08-15 15:34:49,865 INFO  [org.ovirt.engine.core.bll.VdsSelector] (pool-4-thread-44) [2f02a0e6] Checking for a specific VDS only - id:98b8ff54-e519-11e1-9434-0f4490f84999, name:intel-e5530-8-2.englab.nay.redhat.com, host_name(ip):10.66.104.53
>2012-08-15 15:34:49,878 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-44) [2f02a0e6] START, CreateVmVDSCommand(vdsId = b98c6a68-cc23-11e1-ae3d-1fb7cfe60974, vmId=2445ad59-8c84-4315-9119-3f2f4820b946, vm=org.ovirt.engine.core.common.businessentities.VM@4637f1c), log id: 43608463
>2012-08-15 15:34:59,928 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-44) [2f02a0e6] START, CreateVDSCommand(vdsId = b98c6a68-cc23-11e1-ae3d-1fb7cfe60974, vmId=2445ad59-8c84-4315-9119-3f2f4820b946, vm=org.ovirt.engine.core.common.businessentities.VM@4637f1c), log id: 6871622e
>2012-08-15 15:35:00,000 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [75880d44] Checking autorecoverable hosts
>2012-08-15 15:35:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [75880d44] Autorecovering 0 hosts
>2012-08-15 15:35:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [75880d44] Checking autorecoverable hosts done
>2012-08-15 15:35:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [75880d44] Checking autorecoverable storage domains
>2012-08-15 15:35:00,009 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [75880d44] Autorecovering 2 storage domains
>2012-08-15 15:35:00,009 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [75880d44] Autorecovering storage domains: d99e3560-a6fa-4654-8be5-1fd09475d9a7
>2012-08-15 15:35:00,009 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [b3f1e46] Lock Acquired to object EngineLock [exclusiveLocks= key: d99e3560-a6fa-4654-8be5-1fd09475d9a7 value: STORAGE
>, sharedLocks= ]
>2012-08-15 15:35:00,017 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [b3f1e46] Running command: ActivateStorageDomainCommand internal: true. Entities affected :  ID: d99e3560-a6fa-4654-8be5-1fd09475d9a7 Type: Storage
>2012-08-15 15:35:00,026 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [b3f1e46] Lock freed to object EngineLock [exclusiveLocks= key: d99e3560-a6fa-4654-8be5-1fd09475d9a7 value: STORAGE
>, sharedLocks= ]
>2012-08-15 15:35:00,026 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [b3f1e46] ActivateStorage Domain. Before Connect all hosts to pool. Time:8/15/12 3:35 PM
>2012-08-15 15:35:00,036 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [b3f1e46] ActivateStorage Domain. After Connect all hosts to pool. Time:8/15/12 3:35 PM
>2012-08-15 15:35:00,036 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (QuartzScheduler_Worker-91) [b3f1e46] START, ActivateStorageDomainVDSCommand(storagePoolId = 4616ef7b-9438-4ed5-8646-041d05db157f, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = d99e3560-a6fa-4654-8be5-1fd09475d9a7), log id: 5089b45f
>2012-08-15 15:35:00,038 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (QuartzScheduler_Worker-91) [b3f1e46] FINISH, ActivateStorageDomainVDSCommand, log id: 5089b45f
>2012-08-15 15:35:00,038 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [b3f1e46] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: Cannot allocate IRS server
>2012-08-15 15:35:00,039 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [b3f1e46] Command [id=d2c99501-d89c-4ddd-89df-18e8843ff971]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 4616ef7b-9438-4ed5-8646-041d05db157f, storageId = d99e3560-a6fa-4654-8be5-1fd09475d9a7, status=InActive].
>2012-08-15 15:35:00,043 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [b3f1e46] Autorecovering storage domains: c4ec97d9-41ae-487d-ac43-2b840395f072
>2012-08-15 15:35:00,043 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [67159bf6] Lock Acquired to object EngineLock [exclusiveLocks= key: c4ec97d9-41ae-487d-ac43-2b840395f072 value: STORAGE
>, sharedLocks= ]
>2012-08-15 15:35:00,059 WARN  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [67159bf6] CanDoAction of action ActivateStorageDomain failed. Reasons:VAR__TYPE__STORAGE__DOMAIN,VAR__ACTION__ACTIVATE,ACTION_TYPE_FAILED_MASTER_STORAGE_DOMAIN_NOT_ACTIVE
>2012-08-15 15:35:00,059 INFO  [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-91) [67159bf6] Lock freed to object EngineLock [exclusiveLocks= key: c4ec97d9-41ae-487d-ac43-2b840395f072 value: STORAGE
>, sharedLocks= ]
>2012-08-15 15:35:00,059 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-91) [67159bf6] Checking autorecoverable storage domains done
>2012-08-15 15:35:09,273 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-44) [2f02a0e6] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=512,kvmEnable=true,smp=1,emulatedMachine=rhel6.3.0,vmType=kvm,keyboardLayout=en-us,pitReinjection=false,nice=0,display=qxl,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,timeOffset=0,transparentHugePages=true,vmId=2445ad59-8c84-4315-9119-3f2f4820b946,devices=[Ljava.util.Map;@e18cf2c,acpiEnable=true,vmName=rpro,cpuType=Conroe,custom={device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=ecbbe624-f409-4b67-ad54-3e5b6e7eef73,Device=virtio-serial,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=virtio-serial0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=cc02340a-9bf9-42ac-a4aa-91a603849a43,Device=ide,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=ide0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73device_0f6eb766-3a1d-43a6-b487-a861053407f5device_68dc2a6e-7731-47b7-b677-5b3cf7bc95addevice_1b419824-7579-4aa4-a0e2-01650399bf8d=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=1b419824-7579-4aa4-a0e2-01650399bf8d,Device=usb,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x2},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=usb0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73device_0f6eb766-3a1d-43a6-b487-a861053407f5=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=0f6eb766-3a1d-43a6-b487-a861053407f5,Device=unix,Type=channel,BootOrder=0,SpecParams={},Address={port=1, bus=0, controller=0, type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel0, device_cc02340a-9bf9-42ac-a4aa-91a603849a43device_ecbbe624-f409-4b67-ad54-3e5b6e7eef73device_0f6eb766-3a1d-43a6-b487-a861053407f5device_68dc2a6e-7731-47b7-b677-5b3cf7bc95ad=VmId=2445ad59-8c84-4315-9119-3f2f4820b946,DeviceId=68dc2a6e-7731-47b7-b677-5b3cf7bc95ad,Device=spicevmc,Type=channel,BootOrder=0,SpecParams={},Address={port=2, bus=0, controller=0, type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel1}
>2012-08-15 15:35:09,273 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-44) [2f02a0e6] FINISH, CreateVDSCommand, log id: 6871622e
>2012-08-15 15:35:09,273 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-44) [2f02a0e6] FINISH, CreateVDSCommand, log id: 6871622e
>2012-08-15 15:35:09,274 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-44) [2f02a0e6] IncreasePendingVms::CreateVmIncreasing vds intel-e5620-16-6.englab.nay.redhat.com pending vcpu count, now 1. Vm: rpro
>2012-08-15 15:35:09,283 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-44) [2f02a0e6] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 43608463
>2012-08-15 15:35:09,301 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-44) [2f02a0e6] Lock freed to object EngineLock [exclusiveLocks= key: 2445ad59-8c84-4315-9119-3f2f4820b946 value: VM
>, sharedLocks= ]
>2012-08-15 15:35:29,318 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-20) [56f8148c] START, DestroyVDSCommand(vdsId = b98c6a68-cc23-11e1-ae3d-1fb7cfe60974, vmId=2445ad59-8c84-4315-9119-3f2f4820b946, force=false, secondsToWait=0, gracefully=false), log id: 7267bf2f
>2012-08-15 15:35:39,362 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-20) [56f8148c] FINISH, DestroyVDSCommand, log id: 7267bf2f
>2012-08-15 15:35:39,376 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-20) [56f8148c] Running on vds during rerun failed vm: null
>2012-08-15 15:35:39,376 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-20) [56f8148c] vm rpro running in db and not running in vds - add to rerun treatment. vds intel-e5620-16-6.englab.nay.redhat.com
>2012-08-15 15:35:39,392 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-20) [56f8148c] Rerun vm 2445ad59-8c84-4315-9119-3f2f4820b946. Called from vds intel-e5620-16-6.englab.nay.redhat.com
>2012-08-15 15:35:39,409 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-49) [56f8148c] Lock Acquired to object EngineLock [exclusiveLocks= key: 2445ad59-8c84-4315-9119-3f2f4820b946 value: VM
>, sharedLocks= ]
>2012-08-15 15:35:39,416 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-49) [56f8148c] START, IsValidVDSCommand(storagePoolId = a9814774-ab07-46a9-abf8-e42a2fbf8f33, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 2874eafe
>2012-08-15 15:35:39,416 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-4-thread-49) [56f8148c] FINISH, IsValidVDSCommand, return: true, log id: 2874eafe
>2012-08-15 15:35:39,434 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-49) [56f8148c] START, IsVmDuringInitiatingVDSCommand(vmId = 2445ad59-8c84-4315-9119-3f2f4820b946), log id: 415f3007
>2012-08-15 15:35:39,435 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (pool-4-thread-49) [56f8148c] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 415f3007
>2012-08-15 15:35:39,436 INFO  [org.ovirt.engine.core.bll.VdsSelector] (pool-4-thread-49) [56f8148c] Checking for a specific VDS only - id:98b8ff54-e519-11e1-9434-0f4490f84999, name:intel-e5530-8-2.englab.nay.redhat.com, host_name(ip):10.66.104.53
>2012-08-15 15:35:39,452 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-49) [56f8148c] CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,ACTION_TYPE_FAILED_VDS_VM_CLUSTER,VAR__ACTION__RUN,VAR__TYPE__VM,ACTION_TYPE_FAILED_VDS_VM_CLUSTER,ACTION_TYPE_FAILED_VDS_VM_CLUSTER
>2012-08-15 15:35:39,452 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-49) [56f8148c] Lock freed to object EngineLock [exclusiveLocks= key: 2445ad59-8c84-4315-9119-3f2f4820b946 value: VM
>, sharedLocks= ]
>

Actions: View

Attachments on bug 848266: 604499 | 604527