Re: [Users] oVirt 3.1 - VM Migration Issue

--Apple-Mail=_C82D1F4B-8BD2-4AB4-8D7E-7DAE9D5676F1 Content-Transfer-Encoding: quoted-printable Content-Type: text/plain; charset=iso-8859-1 The log is pretty chatty therefore i did another migration attempt and = snipd'd the new=20= --Apple-Mail=_C82D1F4B-8BD2-4AB4-8D7E-7DAE9D5676F1 Content-Disposition: attachment; filename=migrate-dest.log Content-Type: application/octet-stream; name="migrate-dest.log" Content-Transfer-Encoding: quoted-printable Thread-2456765::DEBUG::2013-01-08=20= 10:42:20,119::BindingXMLRPC::161::vds::(wrapper)=20[10.192.42.207]=0A= Thread-2456765::DEBUG::2013-01-08=20= 10:42:20,119::task::568::TaskManager.Task::(_updateState)=20= Task=3D`d9cc7d0f-4fa3-4374-9b65-232df90c5b73`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-2456765::INFO::2013-01-08=20= 10:42:20,119::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= getSpmStatus(spUUID=3D'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20= options=3DNone)=0AThread-2456765::INFO::2013-01-08=20= 10:42:20,120::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= getSpmStatus,=20Return=20response:=20{'spm_st':=20{'spmId':=201,=20= 'spmStatus':=20'SPM',=20'spmLver':=200}}=0A= Thread-2456765::DEBUG::2013-01-08=20= 10:42:20,120::task::1151::TaskManager.Task::(prepare)=20= Task=3D`d9cc7d0f-4fa3-4374-9b65-232df90c5b73`::finished:=20{'spm_st':=20= {'spmId':=201,=20'spmStatus':=20'SPM',=20'spmLver':=200}}=0A= Thread-2456765::DEBUG::2013-01-08=20= 10:42:20,120::task::568::TaskManager.Task::(_updateState)=20= Task=3D`d9cc7d0f-4fa3-4374-9b65-232df90c5b73`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-2456765::DEBUG::2013-01-08=20= 10:42:20,120::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-2456765::DEBUG::2013-01-08=20= 10:42:20,120::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-2456765::DEBUG::2013-01-08=20= 10:42:20,121::task::957::TaskManager.Task::(_decref)=20= Task=3D`d9cc7d0f-4fa3-4374-9b65-232df90c5b73`::ref=200=20aborting=20= False=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,131::BindingXMLRPC::161::vds::(wrapper)=20[10.192.42.207]=0A= Thread-2456766::DEBUG::2013-01-08=20= 10:42:20,131::task::568::TaskManager.Task::(_updateState)=20= Task=3D`e84910c4-d6fb-4907-a445-0751d91d715f`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-2456766::INFO::2013-01-08=20= 10:42:20,132::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= getStoragePoolInfo(spUUID=3D'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20= options=3DNone)=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,132::resourceManager::175::ResourceManager.Request::(__init__)=20= ResName=3D`Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7`ReqID=3D`f3f58cc3-= e5b9-4a3f-8370-b812e40d62cb`::Request=20was=20made=20in=20= '/usr/share/vdsm/storage/resourceManager.py'=20line=20'174'=20at=20= '__init__'=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,132::resourceManager::486::ResourceManager::(registerResource)=20= Trying=20to=20register=20resource=20= 'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7'=20for=20lock=20type=20= 'shared'=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,132::resourceManager::528::ResourceManager::(registerResource)=20= Resource=20'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7'=20is=20free.=20= Now=20locking=20as=20'shared'=20(1=20active=20user)=0A= Thread-2456766::DEBUG::2013-01-08=20= 10:42:20,133::resourceManager::212::ResourceManager.Request::(grant)=20= ResName=3D`Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7`ReqID=3D`f3f58cc3-= e5b9-4a3f-8370-b812e40d62cb`::Granted=20request=0A= Thread-2456766::DEBUG::2013-01-08=20= 10:42:20,133::task::794::TaskManager.Task::(resourceAcquired)=20= Task=3D`e84910c4-d6fb-4907-a445-0751d91d715f`::_resourcesAcquired:=20= Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7=20(shared)=0A= Thread-2456766::DEBUG::2013-01-08=20= 10:42:20,133::task::957::TaskManager.Task::(_decref)=20= Task=3D`e84910c4-d6fb-4907-a445-0751d91d715f`::ref=201=20aborting=20= False=0AThread-2456766::INFO::2013-01-08=20= 10:42:20,137::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= getStoragePoolInfo,=20Return=20response:=20{'info':=20{'spm_id':=201,=20= 'master_uuid':=20'2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'name':=20= 'Default',=20'version':=20'0',=20'domains':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7:Active,ab686999-f320-4a61-ae07-e99c2= f858996:Active,9cff8858-9188-42eb-b089-22f89719394d:Active',=20= 'pool_status':=20'connected',=20'isoprefix':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/ab686999-f320-4a61= -ae07-e99c2f858996/images/11111111-1111-1111-1111-111111111111',=20= 'type':=20'NFS',=20'master_ver':=201,=20'lver':=200},=20'dominfo':=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'status':=20'Active',=20= 'diskfree':=20'177508712448',=20'alerts':=20[],=20'disktotal':=20= '211379027968'},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'status':=20= 'Active',=20'diskfree':=20'177508712448',=20'alerts':=20[],=20= 'disktotal':=20'211379027968'},=20= '9cff8858-9188-42eb-b089-22f89719394d':=20{'status':=20'Active',=20= 'diskfree':=20'177508712448',=20'alerts':=20[],=20'disktotal':=20= '211379027968'}}}=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,137::task::1151::TaskManager.Task::(prepare)=20= Task=3D`e84910c4-d6fb-4907-a445-0751d91d715f`::finished:=20{'info':=20= {'spm_id':=201,=20'master_uuid':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'name':=20'Default',=20= 'version':=20'0',=20'domains':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7:Active,ab686999-f320-4a61-ae07-e99c2= f858996:Active,9cff8858-9188-42eb-b089-22f89719394d:Active',=20= 'pool_status':=20'connected',=20'isoprefix':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/ab686999-f320-4a61= -ae07-e99c2f858996/images/11111111-1111-1111-1111-111111111111',=20= 'type':=20'NFS',=20'master_ver':=201,=20'lver':=200},=20'dominfo':=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'status':=20'Active',=20= 'diskfree':=20'177508712448',=20'alerts':=20[],=20'disktotal':=20= '211379027968'},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'status':=20= 'Active',=20'diskfree':=20'177508712448',=20'alerts':=20[],=20= 'disktotal':=20'211379027968'},=20= '9cff8858-9188-42eb-b089-22f89719394d':=20{'status':=20'Active',=20= 'diskfree':=20'177508712448',=20'alerts':=20[],=20'disktotal':=20= '211379027968'}}}=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,137::task::568::TaskManager.Task::(_updateState)=20= Task=3D`e84910c4-d6fb-4907-a445-0751d91d715f`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,137::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20= {'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7':=20<=20ResourceRef=20= 'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20isValid:=20'True'=20= obj:=20'None'>}=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,138::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,138::resourceManager::538::ResourceManager::(releaseResource)=20= Trying=20to=20release=20resource=20= 'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7'=0A= Thread-2456766::DEBUG::2013-01-08=20= 10:42:20,138::resourceManager::553::ResourceManager::(releaseResource)=20= Released=20resource=20'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7'=20= (0=20active=20users)=0AThread-2456766::DEBUG::2013-01-08=20= 10:42:20,138::resourceManager::558::ResourceManager::(releaseResource)=20= Resource=20'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7'=20is=20free,=20= finding=20out=20if=20anyone=20is=20waiting=20for=20it.=0A= Thread-2456766::DEBUG::2013-01-08=20= 10:42:20,138::resourceManager::565::ResourceManager::(releaseResource)=20= No=20one=20is=20waiting=20for=20resource=20= 'Storage.bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20Clearing=20records.=0A= Thread-2456766::DEBUG::2013-01-08=20= 10:42:20,139::task::957::TaskManager.Task::(_decref)=20= Task=3D`e84910c4-d6fb-4907-a445-0751d91d715f`::ref=200=20aborting=20= False=0AThread-2456768::DEBUG::2013-01-08=20= 10:42:20,892::BindingXMLRPC::883::vds::(wrapper)=20client=20= [10.192.42.196]::call=20vmGetStats=20with=20= ('cfb17b98-1476-4fbf-9fab-7c7f48b60adf',)=20{}=0A= Thread-2456768::DEBUG::2013-01-08=20= 10:42:20,892::BindingXMLRPC::890::vds::(wrapper)=20return=20vmGetStats=20= with=20{'status':=20{'message':=20'Virtual=20machine=20does=20not=20= exist',=20'code':=201}}=0AThread-2456769::DEBUG::2013-01-08=20= 10:42:21,294::BindingXMLRPC::883::vds::(wrapper)=20client=20= [10.192.42.196]::call=20vmMigrationCreate=20with=20({'username':=20= 'Unknown',=20'acpiEnable':=20'true',=20'emulatedMachine':=20'pc',=20= 'afterMigrationStatus':=20'Up',=20'vmId':=20= 'cfb17b98-1476-4fbf-9fab-7c7f48b60adf',=20'transparentHugePages':=20= 'true',=20'displaySecurePort':=20'5905',=20'timeOffset':=20-2,=20= 'cpuType':=20'Nehalem',=20'custom':=20{},=20'pauseCode':=20'NOERR',=20= 'migrationDest':=20'libvirt',=20'smp':=20'1',=20'vmType':=20'kvm',=20= 'spiceSslCipherSuite':=20'DEFAULT',=20'_srcDomXML':=20"<domain=20= type=3D'kvm'=20id=3D'4'>\n=20=20<name>test004.domainname.ads</name>\n=20=20= <uuid>cfb17b98-1476-4fbf-9fab-7c7f48b60adf</uuid>\n=20=20<memory=20= unit=3D'KiB'>1048576</memory>\n=20=20<currentMemory=20= unit=3D'KiB'>1048576</currentMemory>\n=20=20<vcpu=20= placement=3D'static'>1</vcpu>\n=20=20<cputune>\n=20=20=20=20= <shares>1020</shares>\n=20=20</cputune>\n=20=20<sysinfo=20= type=3D'smbios'>\n=20=20=20=20<system>\n=20=20=20=20=20=20<entry=20= name=3D'manufacturer'>oVirt</entry>\n=20=20=20=20=20=20<entry=20= name=3D'product'>oVirt=20Node</entry>\n=20=20=20=20=20=20<entry=20= name=3D'version'>6-3.el6.centos.9</entry>\n=20=20=20=20=20=20<entry=20= name=3D'serial'>55414E03-C241-11DF-BBDA-64093408D485_d4:85:64:09:34:08</en= try>\n=20=20=20=20=20=20<entry=20= name=3D'uuid'>cfb17b98-1476-4fbf-9fab-7c7f48b60adf</entry>\n=20=20=20=20= </system>\n=20=20</sysinfo>\n=20=20<os>\n=20=20=20=20<type=20= arch=3D'x86_64'=20machine=3D'rhel6.3.0'>hvm</type>\n=20=20=20=20<boot=20= dev=3D'hd'/>\n=20=20=20=20<smbios=20mode=3D'sysinfo'/>\n=20=20</os>\n=20=20= <features>\n=20=20=20=20<acpi/>\n=20=20</features>\n=20=20<cpu=20= mode=3D'custom'=20match=3D'exact'>\n=20=20=20=20<model=20= fallback=3D'allow'>Nehalem</model>\n=20=20=20=20<topology=20sockets=3D'1'=20= cores=3D'1'=20threads=3D'1'/>\n=20=20</cpu>\n=20=20<clock=20= offset=3D'variable'=20adjustment=3D'-2'>\n=20=20=20=20<timer=20= name=3D'rtc'=20tickpolicy=3D'catchup'/>\n=20=20</clock>\n=20=20= <on_poweroff>destroy</on_poweroff>\n=20=20= <on_reboot>restart</on_reboot>\n=20=20<on_crash>destroy</on_crash>\n=20=20= <devices>\n=20=20=20=20<emulator>/usr/libexec/qemu-kvm</emulator>\n=20=20= =20=20<disk=20type=3D'file'=20device=3D'cdrom'>\n=20=20=20=20=20=20= <driver=20name=3D'qemu'=20type=3D'raw'/>\n=20=20=20=20=20=20<source=20= file=3D'/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/ab686999-f3= 20-4a61-ae07-e99c2f858996/images/11111111-1111-1111-1111-111111111111/cobb= ler-base.iso'=20startupPolicy=3D'optional'/>\n=20=20=20=20=20=20<target=20= dev=3D'hdc'=20bus=3D'ide'/>\n=20=20=20=20=20=20<readonly/>\n=20=20=20=20=20= =20<serial></serial>\n=20=20=20=20=20=20<alias=20name=3D'ide0-1-0'/>\n=20= =20=20=20=20=20<address=20type=3D'drive'=20controller=3D'0'=20bus=3D'1'=20= target=3D'0'=20unit=3D'0'/>\n=20=20=20=20</disk>\n=20=20=20=20<disk=20= type=3D'file'=20device=3D'disk'=20snapshot=3D'no'>\n=20=20=20=20=20=20= <driver=20name=3D'qemu'=20type=3D'raw'=20cache=3D'none'=20= error_policy=3D'stop'=20io=3D'threads'/>\n=20=20=20=20=20=20<source=20= file=3D'/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9f= a3-4896-b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e= 7b27-cdbb-4dc7-ba7b-4285719615d4'/>\n=20=20=20=20=20=20<target=20= dev=3D'vda'=20bus=3D'virtio'/>\n=20=20=20=20=20=20= <serial>12a6472f-9ab9-436a-8508-b6721cb2b227</serial>\n=20=20=20=20=20=20= <alias=20name=3D'virtio-disk0'/>\n=20=20=20=20=20=20<address=20= type=3D'pci'=20domain=3D'0x0000'=20bus=3D'0x00'=20slot=3D'0x05'=20= function=3D'0x0'/>\n=20=20=20=20</disk>\n=20=20=20=20<controller=20= type=3D'usb'=20index=3D'0'>\n=20=20=20=20=20=20<alias=20name=3D'usb0'/>\n=20= =20=20=20=20=20<address=20type=3D'pci'=20domain=3D'0x0000'=20bus=3D'0x00'=20= slot=3D'0x01'=20function=3D'0x2'/>\n=20=20=20=20</controller>\n=20=20=20=20= <controller=20type=3D'ide'=20index=3D'0'>\n=20=20=20=20=20=20<alias=20= name=3D'ide0'/>\n=20=20=20=20=20=20<address=20type=3D'pci'=20= domain=3D'0x0000'=20bus=3D'0x00'=20slot=3D'0x01'=20function=3D'0x1'/>\n=20= =20=20=20</controller>\n=20=20=20=20<controller=20type=3D'virtio-serial'=20= index=3D'0'>\n=20=20=20=20=20=20<alias=20name=3D'virtio-serial0'/>\n=20=20= =20=20=20=20<address=20type=3D'pci'=20domain=3D'0x0000'=20bus=3D'0x00'=20= slot=3D'0x04'=20function=3D'0x0'/>\n=20=20=20=20</controller>\n=20=20=20=20= <interface=20type=3D'bridge'>\n=20=20=20=20=20=20<mac=20= address=3D'00:1a:4a:c0:2a:05'/>\n=20=20=20=20=20=20<source=20= bridge=3D'ovirtmgmt'/>\n=20=20=20=20=20=20<target=20dev=3D'vnet2'/>\n=20=20= =20=20=20=20<model=20type=3D'virtio'/>\n=20=20=20=20=20=20<alias=20= name=3D'net0'/>\n=20=20=20=20=20=20<address=20type=3D'pci'=20= domain=3D'0x0000'=20bus=3D'0x00'=20slot=3D'0x03'=20function=3D'0x0'/>\n=20= =20=20=20</interface>\n=20=20=20=20<console=20type=3D'pty'=20= tty=3D'/dev/pts/2'>\n=20=20=20=20=20=20<source=20path=3D'/dev/pts/2'/>\n=20= =20=20=20=20=20<target=20type=3D'virtio'=20port=3D'0'/>\n=20=20=20=20=20=20= <alias=20name=3D'console0'/>\n=20=20=20=20</console>\n=20=20=20=20= <channel=20type=3D'unix'>\n=20=20=20=20=20=20<source=20mode=3D'bind'=20= path=3D'/var/lib/libvirt/qemu/channels/test004.domainname.ads.com.redhat.r= hevm.vdsm'/>\n=20=20=20=20=20=20<target=20type=3D'virtio'=20= name=3D'com.redhat.rhevm.vdsm'/>\n=20=20=20=20=20=20<alias=20= name=3D'channel0'/>\n=20=20=20=20=20=20<address=20type=3D'virtio-serial'=20= controller=3D'0'=20bus=3D'0'=20port=3D'1'/>\n=20=20=20=20</channel>\n=20=20= =20=20<channel=20type=3D'unix'>\n=20=20=20=20=20=20<source=20mode=3D'bind'= =20= path=3D'/var/lib/libvirt/qemu/channels/test004.domainname.ads.org.qemu.gue= st_agent.0'/>\n=20=20=20=20=20=20<target=20type=3D'virtio'=20= name=3D'org.qemu.guest_agent.0'/>\n=20=20=20=20=20=20<alias=20= name=3D'channel1'/>\n=20=20=20=20=20=20<address=20type=3D'virtio-serial'=20= controller=3D'0'=20bus=3D'0'=20port=3D'2'/>\n=20=20=20=20</channel>\n=20=20= =20=20<channel=20type=3D'spicevmc'>\n=20=20=20=20=20=20<target=20= type=3D'virtio'=20name=3D'com.redhat.spice.0'/>\n=20=20=20=20=20=20= <alias=20name=3D'channel2'/>\n=20=20=20=20=20=20<address=20= type=3D'virtio-serial'=20controller=3D'0'=20bus=3D'0'=20port=3D'3'/>\n=20= =20=20=20</channel>\n=20=20=20=20<input=20type=3D'mouse'=20bus=3D'ps2'/>\n= =20=20=20=20<graphics=20type=3D'spice'=20port=3D'5904'=20tlsPort=3D'5905'=20= autoport=3D'yes'=20keymap=3D'en-us'=20= passwdValidTo=3D'2012-12-19T15:52:56'=20connected=3D'disconnect'>\n=20=20= =20=20=20=20<listen=20type=3D'network'=20address=3D'10.192.42.196'=20= network=3D'vdsm-ovirtmgmt'/>\n=20=20=20=20=20=20<channel=20name=3D'main'=20= mode=3D'secure'/>\n=20=20=20=20=20=20<channel=20name=3D'display'=20= mode=3D'secure'/>\n=20=20=20=20=20=20<channel=20name=3D'inputs'=20= mode=3D'secure'/>\n=20=20=20=20=20=20<channel=20name=3D'cursor'=20= mode=3D'secure'/>\n=20=20=20=20=20=20<channel=20name=3D'playback'=20= mode=3D'secure'/>\n=20=20=20=20=20=20<channel=20name=3D'record'=20= mode=3D'secure'/>\n=20=20=20=20</graphics>\n=20=20=20=20<video>\n=20=20=20= =20=20=20<model=20type=3D'qxl'=20vram=3D'65536'=20heads=3D'1'/>\n=20=20=20= =20=20=20<alias=20name=3D'video0'/>\n=20=20=20=20=20=20<address=20= type=3D'pci'=20domain=3D'0x0000'=20bus=3D'0x00'=20slot=3D'0x02'=20= function=3D'0x0'/>\n=20=20=20=20</video>\n=20=20=20=20<memballoon=20= model=3D'virtio'>\n=20=20=20=20=20=20<alias=20name=3D'balloon0'/>\n=20=20= =20=20=20=20<address=20type=3D'pci'=20domain=3D'0x0000'=20bus=3D'0x00'=20= slot=3D'0x06'=20function=3D'0x0'/>\n=20=20=20=20</memballoon>\n=20=20= </devices>\n=20=20<seclabel=20type=3D'none'/>\n</domain>\n",=20= 'memSize':=201024,=20'elapsedTimeOffset':=201707876.9180860519,=20= 'vmName':=20'test004.domainname.ads',=20'nice':=20'0',=20'status':=20= 'Up',=20'clientIp':=20'',=20'displayIp':=20'10.192.42.196',=20= 'displayPort':=20'5904',=20'smpCoresPerSocket':=20'1',=20= 'spiceSecureChannels':=20= 'smain,sinputs,scursor,splayback,srecord,sdisplay',=20'nicModel':=20= 'rtl8139,pv',=20'keyboardLayout':=20'en-us',=20'kvmEnable':=20'true',=20= 'pitReinjection':=20'false',=20'displayNetwork':=20'ovirtmgmt',=20= 'devices':=20[{'specParams':=20{'vram':=20'65536'},=20'alias':=20= 'video0',=20'deviceId':=20'0f10b02a-f6bc-432d-bc49-478facf49730',=20= 'address':=20{'slot':=20'0x02',=20'bus':=20'0x00',=20'domain':=20= '0x0000',=20'type':=20'pci',=20'function':=20'0x0'},=20'device':=20= 'qxl',=20'type':=20'video'},=20{'nicModel':=20'pv',=20'macAddr':=20= '00:1a:4a:c0:2a:05',=20'network':=20'ovirtmgmt',=20'alias':=20'net0',=20= 'specParams':=20{},=20'deviceId':=20= '376d1987-d374-486d-8a62-d48ab0a47eb1',=20'address':=20{'slot':=20= '0x03',=20'bus':=20'0x00',=20'domain':=20'0x0000',=20'type':=20'pci',=20= 'function':=20'0x0'},=20'device':=20'bridge',=20'type':=20'interface',=20= 'name':=20'vnet2'},=20{'target':=201048576,=20'specParams':=20{'model':=20= 'virtio'},=20'alias':=20'balloon0',=20'deviceId':=20= '72aaa60a-34e5-41e1-80ea-d9b1c3a1b4e2',=20'address':=20{'slot':=20= '0x06',=20'bus':=20'0x00',=20'domain':=20'0x0000',=20'type':=20'pci',=20= 'function':=20'0x0'},=20'device':=20'memballoon',=20'type':=20= 'balloon'},=20{'index':=20'2',=20'iface':=20'ide',=20'name':=20'hdc',=20= 'alias':=20'ide0-1-0',=20'shared':=20'false',=20'specParams':=20{'path':=20= 'cobbler-base.iso'},=20'readonly':=20'True',=20'deviceId':=20= '58cff440-2f4d-4bbe-94d9-d7c12df85c4d',=20'address':=20{'bus':=20'1',=20= 'controller':=20'0',=20'type':=20'drive',=20'target':=20'0',=20'unit':=20= '0'},=20'device':=20'cdrom',=20'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/ab686999-f320-4a61= -ae07-e99c2f858996/images/11111111-1111-1111-1111-111111111111/cobbler-bas= e.iso',=20'type':=20'disk'},=20{'poolID':=20= 'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20'index':=200,=20'iface':=20= 'virtio',=20'apparentsize':=20'21474836480',=20'alias':=20= 'virtio-disk0',=20'imageID':=20'12a6472f-9ab9-436a-8508-b6721cb2b227',=20= 'readonly':=20'False',=20'shared':=20'false',=20'truesize':=20'0',=20= 'type':=20'disk',=20'domainID':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'reqsize':=20'0',=20'format':=20= 'raw',=20'deviceId':=20'12a6472f-9ab9-436a-8508-b6721cb2b227',=20= 'address':=20{'slot':=20'0x05',=20'bus':=20'0x00',=20'domain':=20= '0x0000',=20'type':=20'pci',=20'function':=20'0x0'},=20'device':=20= 'disk',=20'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'propagateErrors':=20'off',=20'optional':=20= 'false',=20'name':=20'vda',=20'volumeID':=20= '732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20'specParams':=20{},=20= 'volumeChain':=20[{'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'domainID':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'volumeID':=20= '732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20'imageID':=20= '12a6472f-9ab9-436a-8508-b6721cb2b227'}]},=20{'device':=20'usb',=20= 'alias':=20'usb0',=20'type':=20'controller',=20'address':=20{'slot':=20= '0x01',=20'bus':=20'0x00',=20'domain':=20'0x0000',=20'type':=20'pci',=20= 'function':=20'0x2'}},=20{'device':=20'ide',=20'alias':=20'ide0',=20= 'type':=20'controller',=20'address':=20{'slot':=20'0x01',=20'bus':=20= '0x00',=20'domain':=20'0x0000',=20'type':=20'pci',=20'function':=20= '0x1'}},=20{'device':=20'virtio-serial',=20'alias':=20'virtio-serial0',=20= 'type':=20'controller',=20'address':=20{'slot':=20'0x04',=20'bus':=20= '0x00',=20'domain':=20'0x0000',=20'type':=20'pci',=20'function':=20= '0x0'}},=20{'device':=20'unix',=20'alias':=20'channel0',=20'type':=20= 'channel',=20'address':=20{'bus':=20'0',=20'controller':=20'0',=20= 'type':=20'virtio-serial',=20'port':=20'1'}},=20{'device':=20'unix',=20= 'alias':=20'channel1',=20'type':=20'channel',=20'address':=20{'bus':=20= '0',=20'controller':=20'0',=20'type':=20'virtio-serial',=20'port':=20= '2'}},=20{'device':=20'spicevmc',=20'alias':=20'channel2',=20'type':=20= 'channel',=20'address':=20{'bus':=20'0',=20'controller':=20'0',=20= 'type':=20'virtio-serial',=20'port':=20'3'}}],=20'guestIPs':=20'',=20= 'display':=20'qxl'},)=20{}=0AThread-2456769::DEBUG::2013-01-08=20= 10:42:21,294::API::467::vds::(migrationCreate)=20Migration=20create=0A= Thread-2456769::INFO::2013-01-08=20= 10:42:21,329::clientIF::331::vds::(createVm)=20vmContainerLock=20= acquired=20by=20vm=20cfb17b98-1476-4fbf-9fab-7c7f48b60adf=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,334::vm::590::vm.Vm::(_startUnderlyingVm)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Start=0A= Thread-2456769::DEBUG::2013-01-08=20= 10:42:21,335::clientIF::345::vds::(createVm)=20Total=20desktops=20after=20= creation=20of=20cfb17b98-1476-4fbf-9fab-7c7f48b60adf=20is=202=0A= Thread-2456769::DEBUG::2013-01-08=20= 10:42:21,335::libvirtvm::2611::vm.Vm::(waitForMigrationDestinationPrepare)= =20vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::migration=20= destination:=20waiting=20for=20VM=20creation=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,335::vm::594::vm.Vm::(_startUnderlyingVm)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::_ongoingCreations=20= acquired=0AThread-2456770::INFO::2013-01-08=20= 10:42:21,335::libvirtvm::1356::vm.Vm::(_run)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::VM=20wrapper=20has=20= started=0AThread-2456769::DEBUG::2013-01-08=20= 10:42:21,336::libvirtvm::2617::vm.Vm::(waitForMigrationDestinationPrepare)= =20vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::migration=20= destination:=20waiting=2042s=20for=20path=20preparation=0A= Thread-2456770::WARNING::2013-01-08=20= 10:42:21,336::vm::416::vm.Vm::(getConfDevices)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Unknown=20type=20found,=20= device:=20'{'device':=20'unix',=20'alias':=20'channel0',=20'type':=20= 'channel',=20'address':=20{'bus':=20'0',=20'controller':=20'0',=20= 'type':=20'virtio-serial',=20'port':=20'1'}}'=20found=0A= Thread-2456770::WARNING::2013-01-08=20= 10:42:21,336::vm::416::vm.Vm::(getConfDevices)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Unknown=20type=20found,=20= device:=20'{'device':=20'unix',=20'alias':=20'channel1',=20'type':=20= 'channel',=20'address':=20{'bus':=20'0',=20'controller':=20'0',=20= 'type':=20'virtio-serial',=20'port':=20'2'}}'=20found=0A= Thread-2456770::WARNING::2013-01-08=20= 10:42:21,336::vm::416::vm.Vm::(getConfDevices)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Unknown=20type=20found,=20= device:=20'{'device':=20'spicevmc',=20'alias':=20'channel2',=20'type':=20= 'channel',=20'address':=20{'bus':=20'0',=20'controller':=20'0',=20= 'type':=20'virtio-serial',=20'port':=20'3'}}'=20found=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,337::task::568::TaskManager.Task::(_updateState)=20= Task=3D`6f77554d-92e5-4ea2-89e4-3a594a4bbc0f`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-2456770::INFO::2013-01-08=20= 10:42:21,337::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= getVolumeSize(sdUUID=3D'2a1939bd-9fa3-4896-b8a9-46234172aae7',=20= spUUID=3D'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20= imgUUID=3D'12a6472f-9ab9-436a-8508-b6721cb2b227',=20= volUUID=3D'732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20options=3DNone)=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,339::fileVolume::558::Storage.Volume::(validateVolumePath)=20= validate=20path=20for=20732e7b27-cdbb-4dc7-ba7b-4285719615d4=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,342::fileVolume::558::Storage.Volume::(validateVolumePath)=20= validate=20path=20for=20732e7b27-cdbb-4dc7-ba7b-4285719615d4=0A= Thread-2456770::INFO::2013-01-08=20= 10:42:21,343::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= getVolumeSize,=20Return=20response:=20{'truesize':=20'2936401920',=20= 'apparentsize':=20'21474836480'}=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,343::task::1151::TaskManager.Task::(prepare)=20= Task=3D`6f77554d-92e5-4ea2-89e4-3a594a4bbc0f`::finished:=20{'truesize':=20= '2936401920',=20'apparentsize':=20'21474836480'}=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,344::task::568::TaskManager.Task::(_updateState)=20= Task=3D`6f77554d-92e5-4ea2-89e4-3a594a4bbc0f`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,344::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,344::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,344::task::957::TaskManager.Task::(_decref)=20= Task=3D`6f77554d-92e5-4ea2-89e4-3a594a4bbc0f`::ref=200=20aborting=20= False=0AThread-2456770::INFO::2013-01-08=20= 10:42:21,344::clientIF::313::vds::(prepareVolumePath)=20prepared=20= volume=20path:=20= /rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/ab686999-f320-4a61-= ae07-e99c2f858996/images/11111111-1111-1111-1111-111111111111/cobbler-base= .iso=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,345::task::568::TaskManager.Task::(_updateState)=20= Task=3D`023694dd-13d1-4507-8a25-9997e00a0067`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-2456770::INFO::2013-01-08=20= 10:42:21,345::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= prepareImage(sdUUID=3D'2a1939bd-9fa3-4896-b8a9-46234172aae7',=20= spUUID=3D'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20= imgUUID=3D'12a6472f-9ab9-436a-8508-b6721cb2b227',=20= volUUID=3D'732e7b27-cdbb-4dc7-ba7b-4285719615d4')=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,345::resourceManager::175::ResourceManager.Request::(__init__)=20= ResName=3D`Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7`ReqID=3D`dc05f7b7-= 01b4-41e6-8dfc-5879d8a45e1c`::Request=20was=20made=20in=20= '/usr/share/vdsm/storage/resourceManager.py'=20line=20'174'=20at=20= '__init__'=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,345::resourceManager::486::ResourceManager::(registerResource)=20= Trying=20to=20register=20resource=20= 'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7'=20for=20lock=20type=20= 'shared'=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,346::resourceManager::528::ResourceManager::(registerResource)=20= Resource=20'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7'=20is=20free.=20= Now=20locking=20as=20'shared'=20(1=20active=20user)=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,346::resourceManager::212::ResourceManager.Request::(grant)=20= ResName=3D`Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7`ReqID=3D`dc05f7b7-= 01b4-41e6-8dfc-5879d8a45e1c`::Granted=20request=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,346::task::794::TaskManager.Task::(resourceAcquired)=20= Task=3D`023694dd-13d1-4507-8a25-9997e00a0067`::_resourcesAcquired:=20= Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7=20(shared)=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,346::task::957::TaskManager.Task::(_decref)=20= Task=3D`023694dd-13d1-4507-8a25-9997e00a0067`::ref=201=20aborting=20= False=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,347::fileVolume::558::Storage.Volume::(validateVolumePath)=20= validate=20path=20for=20732e7b27-cdbb-4dc7-ba7b-4285719615d4=0A= Thread-2456770::INFO::2013-01-08=20= 10:42:21,368::image::322::Storage.Image::(getChain)=20= sdUUID=3D2a1939bd-9fa3-4896-b8a9-46234172aae7=20= imgUUID=3D12a6472f-9ab9-436a-8508-b6721cb2b227=20= chain=3D[<storage.fileVolume.FileVolume=20object=20at=200x7f7d946190d0>]=20= =0AThread-2456770::INFO::2013-01-08=20= 10:42:21,370::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= prepareImage,=20Return=20response:=20{'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'chain':=20[{'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'domainID':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'volumeID':=20= '732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20'imageID':=20= '12a6472f-9ab9-436a-8508-b6721cb2b227'}]}=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,370::task::1151::TaskManager.Task::(prepare)=20= Task=3D`023694dd-13d1-4507-8a25-9997e00a0067`::finished:=20{'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'chain':=20[{'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'domainID':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'volumeID':=20= '732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20'imageID':=20= '12a6472f-9ab9-436a-8508-b6721cb2b227'}]}=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,370::task::568::TaskManager.Task::(_updateState)=20= Task=3D`023694dd-13d1-4507-8a25-9997e00a0067`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,370::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20= {'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7':=20<=20ResourceRef=20= 'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7',=20isValid:=20'True'=20= obj:=20'None'>}=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,370::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,370::resourceManager::538::ResourceManager::(releaseResource)=20= Trying=20to=20release=20resource=20= 'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7'=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,371::resourceManager::553::ResourceManager::(releaseResource)=20= Released=20resource=20'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7'=20= (0=20active=20users)=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,371::resourceManager::558::ResourceManager::(releaseResource)=20= Resource=20'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7'=20is=20free,=20= finding=20out=20if=20anyone=20is=20waiting=20for=20it.=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,371::resourceManager::565::ResourceManager::(releaseResource)=20= No=20one=20is=20waiting=20for=20resource=20= 'Storage.2a1939bd-9fa3-4896-b8a9-46234172aae7',=20Clearing=20records.=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,371::task::957::TaskManager.Task::(_decref)=20= Task=3D`023694dd-13d1-4507-8a25-9997e00a0067`::ref=200=20aborting=20= False=0AThread-2456770::INFO::2013-01-08=20= 10:42:21,371::clientIF::313::vds::(prepareVolumePath)=20prepared=20= volume=20path:=20= /rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896-= b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cdb= b-4dc7-ba7b-4285719615d4=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,376::misc::83::Storage.Misc.excCmd::(<lambda>)=20'/usr/bin/sudo=20= -n=20/sbin/service=20ksmtuned=20retune'=20(cwd=20None)=0A= Thread-2456769::DEBUG::2013-01-08=20= 10:42:21,376::API::480::vds::(migrationCreate)=20Destination=20VM=20= creation=20succeeded=0AThread-2456769::DEBUG::2013-01-08=20= 10:42:21,383::BindingXMLRPC::890::vds::(wrapper)=20return=20= vmMigrationCreate=20with=20{'status':=20{'message':=20'Done',=20'code':=20= 0},=20'migrationPort':=200,=20'params':=20{'status':=20'Migration=20= Destination',=20'acpiEnable':=20'true',=20'emulatedMachine':=20'pc',=20= 'afterMigrationStatus':=20'Up',=20'pid':=20'0',=20= 'transparentHugePages':=20'true',=20'displaySecurePort':=20'-1',=20= 'timeOffset':=20-2,=20'cpuType':=20'Nehalem',=20'smp':=20'1',=20= 'pauseCode':=20'NOERR',=20'migrationDest':=20'libvirt',=20'custom':=20= {},=20'vmType':=20'kvm',=20'spiceSslCipherSuite':=20'DEFAULT',=20= 'memSize':=201024,=20'vmName':=20'test004.domainname.ads',=20'nice':=20= '0',=20'username':=20'Unknown',=20'vmId':=20= 'cfb17b98-1476-4fbf-9fab-7c7f48b60adf',=20'displayIp':=20= '10.192.42.165',=20'keyboardLayout':=20'en-us',=20'displayPort':=20'-1',=20= 'guestIPs':=20'',=20'spiceSecureChannels':=20= 'smain,sinputs,scursor,splayback,srecord,sdisplay',=20'nicModel':=20= 'rtl8139,pv',=20'smpCoresPerSocket':=20'1',=20'kvmEnable':=20'true',=20= 'pitReinjection':=20'false',=20'displayNetwork':=20'ovirtmgmt',=20= 'devices':=20[{'device':=20'usb',=20'alias':=20'usb0',=20'type':=20= 'controller',=20'address':=20{'slot':=20'0x01',=20'bus':=20'0x00',=20= 'domain':=20'0x0000',=20'type':=20'pci',=20'function':=20'0x2'}},=20= {'device':=20'ide',=20'alias':=20'ide0',=20'type':=20'controller',=20= 'address':=20{'slot':=20'0x01',=20'bus':=20'0x00',=20'domain':=20= '0x0000',=20'type':=20'pci',=20'function':=20'0x1'}},=20{'device':=20= 'virtio-serial',=20'alias':=20'virtio-serial0',=20'type':=20= 'controller',=20'address':=20{'slot':=20'0x04',=20'bus':=20'0x00',=20= 'domain':=20'0x0000',=20'type':=20'pci',=20'function':=20'0x0'}},=20= {'specParams':=20{'vram':=20'65536'},=20'alias':=20'video0',=20= 'deviceId':=20'0f10b02a-f6bc-432d-bc49-478facf49730',=20'address':=20= {'slot':=20'0x02',=20'bus':=20'0x00',=20'domain':=20'0x0000',=20'type':=20= 'pci',=20'function':=20'0x0'},=20'device':=20'qxl',=20'type':=20= 'video'},=20{'nicModel':=20'pv',=20'macAddr':=20'00:1a:4a:c0:2a:05',=20= 'network':=20'ovirtmgmt',=20'alias':=20'net0',=20'specParams':=20{},=20= 'deviceId':=20'376d1987-d374-486d-8a62-d48ab0a47eb1',=20'address':=20= {'slot':=20'0x03',=20'bus':=20'0x00',=20'domain':=20'0x0000',=20'type':=20= 'pci',=20'function':=20'0x0'},=20'device':=20'bridge',=20'type':=20= 'interface',=20'name':=20'vnet2'},=20{'target':=201048576,=20= 'specParams':=20{'model':=20'virtio'},=20'alias':=20'balloon0',=20= 'deviceId':=20'72aaa60a-34e5-41e1-80ea-d9b1c3a1b4e2',=20'address':=20= {'slot':=20'0x06',=20'bus':=20'0x00',=20'domain':=20'0x0000',=20'type':=20= 'pci',=20'function':=20'0x0'},=20'device':=20'memballoon',=20'type':=20= 'balloon'},=20{'index':=20'2',=20'iface':=20'ide',=20'name':=20'hdc',=20= 'alias':=20'ide0-1-0',=20'shared':=20'false',=20'specParams':=20{'path':=20= 'cobbler-base.iso'},=20'readonly':=20'True',=20'deviceId':=20= '58cff440-2f4d-4bbe-94d9-d7c12df85c4d',=20'address':=20{'bus':=20'1',=20= 'controller':=20'0',=20'type':=20'drive',=20'target':=20'0',=20'unit':=20= '0'},=20'device':=20'cdrom',=20'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/ab686999-f320-4a61= -ae07-e99c2f858996/images/11111111-1111-1111-1111-111111111111/cobbler-bas= e.iso',=20'type':=20'disk'},=20{'poolID':=20= 'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20'index':=200,=20'iface':=20= 'virtio',=20'apparentsize':=20'21474836480',=20'alias':=20= 'virtio-disk0',=20'imageID':=20'12a6472f-9ab9-436a-8508-b6721cb2b227',=20= 'readonly':=20'False',=20'shared':=20'false',=20'truesize':=20= '2936401920',=20'type':=20'disk',=20'domainID':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'reqsize':=20'0',=20'format':=20= 'raw',=20'deviceId':=20'12a6472f-9ab9-436a-8508-b6721cb2b227',=20= 'address':=20{'slot':=20'0x05',=20'bus':=20'0x00',=20'domain':=20= '0x0000',=20'type':=20'pci',=20'function':=20'0x0'},=20'device':=20= 'disk',=20'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'propagateErrors':=20'off',=20'optional':=20= 'false',=20'name':=20'vda',=20'volumeID':=20= '732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20'specParams':=20{},=20= 'volumeChain':=20[{'path':=20= '/rhev/data-center/bb0beebf-edab-41e2-83b8-16bdbbc5dda7/2a1939bd-9fa3-4896= -b8a9-46234172aae7/images/12a6472f-9ab9-436a-8508-b6721cb2b227/732e7b27-cd= bb-4dc7-ba7b-4285719615d4',=20'domainID':=20= '2a1939bd-9fa3-4896-b8a9-46234172aae7',=20'volumeID':=20= '732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20'imageID':=20= '12a6472f-9ab9-436a-8508-b6721cb2b227'}]},=20{'device':=20'unix',=20= 'alias':=20'channel0',=20'type':=20'channel',=20'address':=20{'bus':=20= '0',=20'controller':=20'0',=20'type':=20'virtio-serial',=20'port':=20= '1'}},=20{'device':=20'unix',=20'alias':=20'channel1',=20'type':=20= 'channel',=20'address':=20{'bus':=20'0',=20'controller':=20'0',=20= 'type':=20'virtio-serial',=20'port':=20'2'}},=20{'device':=20'spicevmc',=20= 'alias':=20'channel2',=20'type':=20'channel',=20'address':=20{'bus':=20= '0',=20'controller':=20'0',=20'type':=20'virtio-serial',=20'port':=20= '3'}}],=20'clientIp':=20'',=20'display':=20'qxl'}}=0A= Thread-2456770::DEBUG::2013-01-08=20= 10:42:21,422::misc::83::Storage.Misc.excCmd::(<lambda>)=20SUCCESS:=20= <err>=20=3D=20'';=20<rc>=20=3D=200=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,423::vm::611::vm.Vm::(_startUnderlyingVm)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::_ongoingCreations=20= released=0AThread-2456770::DEBUG::2013-01-08=20= 10:42:21,423::libvirtvm::1669::vm.Vm::(_waitForIncomingMigrationFinish)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Waiting=20300=20seconds=20= for=20end=20of=20migration=0AThread-2456772::DEBUG::2013-01-08=20= 10:42:22,523::task::568::TaskManager.Task::(_updateState)=20= Task=3D`16f59db2-fcff-4f03-b964-1ed70965d150`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-2456772::INFO::2013-01-08=20= 10:42:22,523::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats(options=3DNone)=0AThread-2456772::INFO::2013-01-08=20= 10:42:22,524::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats,=20Return=20response:=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'delay':=20= '0.00229406356812',=20'lastCheck':=20'8.0',=20'code':=200,=20'valid':=20= True},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'delay':=20= '0.00214695930481',=20'lastCheck':=20'6.3',=20'code':=200,=20'valid':=20= True},=20'9cff8858-9188-42eb-b089-22f89719394d':=20{'delay':=20= '0.00227403640747',=20'lastCheck':=20'6.4',=20'code':=200,=20'valid':=20= True}}=0AThread-2456772::DEBUG::2013-01-08=20= 10:42:22,524::task::1151::TaskManager.Task::(prepare)=20= Task=3D`16f59db2-fcff-4f03-b964-1ed70965d150`::finished:=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'delay':=20= '0.00229406356812',=20'lastCheck':=20'8.0',=20'code':=200,=20'valid':=20= True},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'delay':=20= '0.00214695930481',=20'lastCheck':=20'6.3',=20'code':=200,=20'valid':=20= True},=20'9cff8858-9188-42eb-b089-22f89719394d':=20{'delay':=20= '0.00227403640747',=20'lastCheck':=20'6.4',=20'code':=200,=20'valid':=20= True}}=0AThread-2456772::DEBUG::2013-01-08=20= 10:42:22,524::task::568::TaskManager.Task::(_updateState)=20= Task=3D`16f59db2-fcff-4f03-b964-1ed70965d150`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-2456772::DEBUG::2013-01-08=20= 10:42:22,524::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-2456772::DEBUG::2013-01-08=20= 10:42:22,524::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-2456772::DEBUG::2013-01-08=20= 10:42:22,524::task::957::TaskManager.Task::(_decref)=20= Task=3D`16f59db2-fcff-4f03-b964-1ed70965d150`::ref=200=20aborting=20= False=0AThread-2456773::DEBUG::2013-01-08=20= 10:42:22,537::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`a08884f6-d634-4d1d-a0ef-f77cd96f0953`::Disk=20vda=20latency=20= not=20available=0AThread-2456774::DEBUG::2013-01-08=20= 10:42:22,717::BindingXMLRPC::883::vds::(wrapper)=20client=20= [10.192.42.196]::call=20vmDestroy=20with=20= ('cfb17b98-1476-4fbf-9fab-7c7f48b60adf',)=20{}=0A= Thread-2456774::INFO::2013-01-08=20= 10:42:22,717::API::308::vds::(destroy)=20vmContainerLock=20acquired=20by=20= vm=20cfb17b98-1476-4fbf-9fab-7c7f48b60adf=0A= Thread-2456774::DEBUG::2013-01-08=20= 10:42:22,718::libvirtvm::2209::vm.Vm::(destroy)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::destroy=20Called=0A= Thread-2456774::INFO::2013-01-08=20= 10:42:22,718::libvirtvm::2160::vm.Vm::(releaseVm)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Release=20VM=20resources=0A= Thread-2456774::DEBUG::2013-01-08=20= 10:42:22,718::misc::83::Storage.Misc.excCmd::(<lambda>)=20'/usr/bin/sudo=20= -n=20/sbin/service=20ksmtuned=20retune'=20(cwd=20None)=0A= Thread-2456774::DEBUG::2013-01-08=20= 10:42:22,755::misc::83::Storage.Misc.excCmd::(<lambda>)=20SUCCESS:=20= <err>=20=3D=20'';=20<rc>=20=3D=200=0AThread-2456774::DEBUG::2013-01-08=20= 10:42:22,756::task::568::TaskManager.Task::(_updateState)=20= Task=3D`19304f16-6634-40f2-aeb5-081cb423eb7d`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-2456774::INFO::2013-01-08=20= 10:42:22,756::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= inappropriateDevices(thiefId=3D'cfb17b98-1476-4fbf-9fab-7c7f48b60adf')=0A= Thread-2456774::INFO::2013-01-08=20= 10:42:22,769::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= inappropriateDevices,=20Return=20response:=20None=0A= Thread-2456774::DEBUG::2013-01-08=20= 10:42:22,770::task::1151::TaskManager.Task::(prepare)=20= Task=3D`19304f16-6634-40f2-aeb5-081cb423eb7d`::finished:=20None=0A= Thread-2456774::DEBUG::2013-01-08=20= 10:42:22,770::task::568::TaskManager.Task::(_updateState)=20= Task=3D`19304f16-6634-40f2-aeb5-081cb423eb7d`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-2456774::DEBUG::2013-01-08=20= 10:42:22,770::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-2456774::DEBUG::2013-01-08=20= 10:42:22,770::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-2456774::DEBUG::2013-01-08=20= 10:42:22,770::task::957::TaskManager.Task::(_decref)=20= Task=3D`19304f16-6634-40f2-aeb5-081cb423eb7d`::ref=200=20aborting=20= False=0AThread-2456774::DEBUG::2013-01-08=20= 10:42:22,771::libvirtvm::2203::vm.Vm::(deleteVm)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Total=20desktops=20after=20= destroy=20of=20cfb17b98-1476-4fbf-9fab-7c7f48b60adf=20is=201=0A= Thread-2456774::DEBUG::2013-01-08=20= 10:42:22,771::BindingXMLRPC::890::vds::(wrapper)=20return=20vmDestroy=20= with=20{'status':=20{'message':=20'Machine=20destroyed',=20'code':=200}}=0A= --Apple-Mail=_C82D1F4B-8BD2-4AB4-8D7E-7DAE9D5676F1 Content-Disposition: attachment; filename=migrate-src.log Content-Type: application/octet-stream; name="migrate-src.log" Content-Transfer-Encoding: quoted-printable Thread-1484330::DEBUG::2013-01-08=20= 10:40:59,186::task::568::TaskManager.Task::(_updateState)=20= Task=3D`7417efd1-9122-4c1c-8f64-b2df0bcbc07d`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-1484330::INFO::2013-01-08=20= 10:40:59,186::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats(options=3DNone)=0AThread-1484330::INFO::2013-01-08=20= 10:40:59,186::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats,=20Return=20response:=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'delay':=20= '0.00235295295715',=20'lastCheck':=20'1.9',=20'code':=200,=20'valid':=20= True},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'delay':=20= '0.00221991539001',=20'lastCheck':=20'0.8',=20'code':=200,=20'valid':=20= True},=20'9cff8858-9188-42eb-b089-22f89719394d':=20{'delay':=20= '0.00199294090271',=20'lastCheck':=20'0.8',=20'code':=200,=20'valid':=20= True}}=0AThread-1484330::DEBUG::2013-01-08=20= 10:40:59,186::task::1151::TaskManager.Task::(prepare)=20= Task=3D`7417efd1-9122-4c1c-8f64-b2df0bcbc07d`::finished:=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'delay':=20= '0.00235295295715',=20'lastCheck':=20'1.9',=20'code':=200,=20'valid':=20= True},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'delay':=20= '0.00221991539001',=20'lastCheck':=20'0.8',=20'code':=200,=20'valid':=20= True},=20'9cff8858-9188-42eb-b089-22f89719394d':=20{'delay':=20= '0.00199294090271',=20'lastCheck':=20'0.8',=20'code':=200,=20'valid':=20= True}}=0AThread-1484330::DEBUG::2013-01-08=20= 10:40:59,186::task::568::TaskManager.Task::(_updateState)=20= Task=3D`7417efd1-9122-4c1c-8f64-b2df0bcbc07d`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-1484330::DEBUG::2013-01-08=20= 10:40:59,186::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-1484330::DEBUG::2013-01-08=20= 10:40:59,187::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-1484330::DEBUG::2013-01-08=20= 10:40:59,187::task::957::TaskManager.Task::(_decref)=20= Task=3D`7417efd1-9122-4c1c-8f64-b2df0bcbc07d`::ref=200=20aborting=20= False=0AThread-1484331::DEBUG::2013-01-08=20= 10:40:59,402::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Disk=20vda=20latency=20= not=20available=0AThread-1484331::DEBUG::2013-01-08=20= 10:40:59,402::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`7b8f725b-0a67-46d4-a3cf-db43daad0c42`::Disk=20vda=20latency=20= not=20available=0AThread-1484331::DEBUG::2013-01-08=20= 10:40:59,402::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`9dc63ce4-0f76-4963-adfe-6f8eb1a44806`::Disk=20vda=20latency=20= not=20available=0AThread-1484331::DEBUG::2013-01-08=20= 10:40:59,403::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`e8683e88-f3f2-4fe9-80f7-f4888d8e7a13`::Disk=20vda=20latency=20= not=20available=0AThread-417786::DEBUG::2013-01-08=20= 10:41:00,312::task::568::TaskManager.Task::(_updateState)=20= Task=3D`966b7fee-f32b-4c1e-914f-b40a8a7ff75b`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-417786::INFO::2013-01-08=20= 10:41:00,312::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= getVolumeSize(sdUUID=3D'2a1939bd-9fa3-4896-b8a9-46234172aae7',=20= spUUID=3D'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20= imgUUID=3D'12a6472f-9ab9-436a-8508-b6721cb2b227',=20= volUUID=3D'732e7b27-cdbb-4dc7-ba7b-4285719615d4',=20options=3DNone)=0A= Thread-417786::DEBUG::2013-01-08=20= 10:41:00,314::fileVolume::558::Storage.Volume::(validateVolumePath)=20= validate=20path=20for=20732e7b27-cdbb-4dc7-ba7b-4285719615d4=0A= Thread-417786::DEBUG::2013-01-08=20= 10:41:00,317::fileVolume::558::Storage.Volume::(validateVolumePath)=20= validate=20path=20for=20732e7b27-cdbb-4dc7-ba7b-4285719615d4=0A= Thread-417786::INFO::2013-01-08=20= 10:41:00,319::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= getVolumeSize,=20Return=20response:=20{'truesize':=20'2936401920',=20= 'apparentsize':=20'21474836480'}=0AThread-417786::DEBUG::2013-01-08=20= 10:41:00,319::task::1151::TaskManager.Task::(prepare)=20= Task=3D`966b7fee-f32b-4c1e-914f-b40a8a7ff75b`::finished:=20{'truesize':=20= '2936401920',=20'apparentsize':=20'21474836480'}=0A= Thread-417786::DEBUG::2013-01-08=20= 10:41:00,319::task::568::TaskManager.Task::(_updateState)=20= Task=3D`966b7fee-f32b-4c1e-914f-b40a8a7ff75b`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-417786::DEBUG::2013-01-08=20= 10:41:00,319::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-417786::DEBUG::2013-01-08=20= 10:41:00,319::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-417786::DEBUG::2013-01-08=20= 10:41:00,320::task::957::TaskManager.Task::(_decref)=20= Task=3D`966b7fee-f32b-4c1e-914f-b40a8a7ff75b`::ref=200=20aborting=20= False=0AThread-1284213::DEBUG::2013-01-08=20= 10:41:00,325::task::568::TaskManager.Task::(_updateState)=20= Task=3D`f9a27440-7e81-4f9a-918b-59db7a0173e9`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-1284213::INFO::2013-01-08=20= 10:41:00,326::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= getVolumeSize(sdUUID=3D'2a1939bd-9fa3-4896-b8a9-46234172aae7',=20= spUUID=3D'bb0beebf-edab-41e2-83b8-16bdbbc5dda7',=20= imgUUID=3D'8f2e2e18-0f8b-4e35-bdaf-8c853d8fb090',=20= volUUID=3D'7a569331-46e2-4e53-b8a9-9ef51290125e',=20options=3DNone)=0A= Thread-1284213::DEBUG::2013-01-08=20= 10:41:00,328::fileVolume::558::Storage.Volume::(validateVolumePath)=20= validate=20path=20for=207a569331-46e2-4e53-b8a9-9ef51290125e=0A= Thread-1284213::DEBUG::2013-01-08=20= 10:41:00,331::fileVolume::558::Storage.Volume::(validateVolumePath)=20= validate=20path=20for=207a569331-46e2-4e53-b8a9-9ef51290125e=0A= Thread-1284213::INFO::2013-01-08=20= 10:41:00,332::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= getVolumeSize,=20Return=20response:=20{'truesize':=20'10737422336',=20= 'apparentsize':=20'10737418240'}=0AThread-1284213::DEBUG::2013-01-08=20= 10:41:00,332::task::1151::TaskManager.Task::(prepare)=20= Task=3D`f9a27440-7e81-4f9a-918b-59db7a0173e9`::finished:=20{'truesize':=20= '10737422336',=20'apparentsize':=20'10737418240'}=0A= Thread-1284213::DEBUG::2013-01-08=20= 10:41:00,332::task::568::TaskManager.Task::(_updateState)=20= Task=3D`f9a27440-7e81-4f9a-918b-59db7a0173e9`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-1284213::DEBUG::2013-01-08=20= 10:41:00,332::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-1284213::DEBUG::2013-01-08=20= 10:41:00,332::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-1284213::DEBUG::2013-01-08=20= 10:41:00,333::task::957::TaskManager.Task::(_decref)=20= Task=3D`f9a27440-7e81-4f9a-918b-59db7a0173e9`::ref=200=20aborting=20= False=0A=0A=0A=0A=0A=0A=0A=0A=0A=0A=0A=0A=0A= Thread-1484336::DEBUG::2013-01-08=20= 10:41:07,659::BindingXMLRPC::883::vds::(wrapper)=20client=20= [10.192.42.207]::call=20vmMigrate=20with=20({'src':=20'10.192.42.196',=20= 'dst':=20'10.192.42.165:54321',=20'vmId':=20= 'cfb17b98-1476-4fbf-9fab-7c7f48b60adf',=20'method':=20'online'},)=20{}=20= flowID=20[13b25d64]=0AThread-1484336::DEBUG::2013-01-08=20= 10:41:07,659::API::432::vds::(migrate)=20{'src':=20'10.192.42.196',=20= 'dst':=20'10.192.42.165:54321',=20'vmId':=20= 'cfb17b98-1476-4fbf-9fab-7c7f48b60adf',=20'method':=20'online'}=0A= Thread-1484337::DEBUG::2013-01-08=20= 10:41:07,660::vm::125::vm.Vm::(_setupVdsConnection)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Destination=20server=20= is:=2010.192.42.165:54321=0AThread-1484336::DEBUG::2013-01-08=20= 10:41:07,660::BindingXMLRPC::890::vds::(wrapper)=20return=20vmMigrate=20= with=20{'status':=20{'message':=20'Migration=20process=20starting',=20= 'code':=200}}=0AThread-1484337::DEBUG::2013-01-08=20= 10:41:07,660::vm::127::vm.Vm::(_setupVdsConnection)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Initiating=20connection=20= with=20destination=0AThread-1484337::DEBUG::2013-01-08=20= 10:41:07,752::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Disk=20vda=20latency=20= not=20available=0AThread-1484337::DEBUG::2013-01-08=20= 10:41:07,835::vm::173::vm.Vm::(_prepareGuest)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::migration=20Process=20= begins=0AThread-1484337::DEBUG::2013-01-08=20= 10:41:07,927::vm::237::vm.Vm::(run)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::migration=20semaphore=20= acquired=0AThread-1484337::DEBUG::2013-01-08=20= 10:41:08,251::libvirtvm::449::vm.Vm::(_startUnderlyingMigration)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::starting=20migration=20to=20= qemu+tls://10.192.42.165/system=0AThread-1484338::DEBUG::2013-01-08=20= 10:41:08,251::libvirtvm::335::vm.Vm::(run)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::migration=20downtime=20= thread=20started=0AThread-1484339::DEBUG::2013-01-08=20= 10:41:08,252::libvirtvm::371::vm.Vm::(run)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::starting=20migration=20= monitor=20thread=0AThread-1484337::DEBUG::2013-01-08=20= 10:41:09,521::libvirtvm::350::vm.Vm::(cancel)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::canceling=20migration=20= downtime=20thread=0AThread-1484337::DEBUG::2013-01-08=20= 10:41:09,521::libvirtvm::409::vm.Vm::(stop)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::stopping=20migration=20= monitor=20thread=0AThread-1484338::DEBUG::2013-01-08=20= 10:41:09,522::libvirtvm::347::vm.Vm::(run)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::migration=20downtime=20= thread=20exiting=0AThread-1484337::ERROR::2013-01-08=20= 10:41:09,522::vm::179::vm.Vm::(_recover)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::internal=20error=20= Process=20exited=20while=20reading=20console=20log=20output:=20=0A= Thread-1484340::DEBUG::2013-01-08=20= 10:41:09,544::task::568::TaskManager.Task::(_updateState)=20= Task=3D`bfebf940-d2a3-4b6c-948b-cac951a686bf`::moving=20from=20state=20= init=20->=20state=20preparing=0AThread-1484340::INFO::2013-01-08=20= 10:41:09,544::logUtils::37::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats(options=3DNone)=0AThread-1484340::INFO::2013-01-08=20= 10:41:09,544::logUtils::39::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats,=20Return=20response:=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'delay':=20= '0.00229001045227',=20'lastCheck':=20'2.2',=20'code':=200,=20'valid':=20= True},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'delay':=20= '0.00204110145569',=20'lastCheck':=20'1.1',=20'code':=200,=20'valid':=20= True},=20'9cff8858-9188-42eb-b089-22f89719394d':=20{'delay':=20= '0.00174999237061',=20'lastCheck':=20'1.1',=20'code':=200,=20'valid':=20= True}}=0AThread-1484340::DEBUG::2013-01-08=20= 10:41:09,544::task::1151::TaskManager.Task::(prepare)=20= Task=3D`bfebf940-d2a3-4b6c-948b-cac951a686bf`::finished:=20= {'2a1939bd-9fa3-4896-b8a9-46234172aae7':=20{'delay':=20= '0.00229001045227',=20'lastCheck':=20'2.2',=20'code':=200,=20'valid':=20= True},=20'ab686999-f320-4a61-ae07-e99c2f858996':=20{'delay':=20= '0.00204110145569',=20'lastCheck':=20'1.1',=20'code':=200,=20'valid':=20= True},=20'9cff8858-9188-42eb-b089-22f89719394d':=20{'delay':=20= '0.00174999237061',=20'lastCheck':=20'1.1',=20'code':=200,=20'valid':=20= True}}=0AThread-1484340::DEBUG::2013-01-08=20= 10:41:09,544::task::568::TaskManager.Task::(_updateState)=20= Task=3D`bfebf940-d2a3-4b6c-948b-cac951a686bf`::moving=20from=20state=20= preparing=20->=20state=20finished=0AThread-1484340::DEBUG::2013-01-08=20= 10:41:09,545::resourceManager::809::ResourceManager.Owner::(releaseAll)=20= Owner.releaseAll=20requests=20{}=20resources=20{}=0A= Thread-1484340::DEBUG::2013-01-08=20= 10:41:09,545::resourceManager::844::ResourceManager.Owner::(cancelAll)=20= Owner.cancelAll=20requests=20{}=0AThread-1484340::DEBUG::2013-01-08=20= 10:41:09,545::task::957::TaskManager.Task::(_decref)=20= Task=3D`bfebf940-d2a3-4b6c-948b-cac951a686bf`::ref=200=20aborting=20= False=0AThread-1484341::DEBUG::2013-01-08=20= 10:41:09,558::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Disk=20vda=20latency=20= not=20available=0AThread-1484341::DEBUG::2013-01-08=20= 10:41:09,559::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`7b8f725b-0a67-46d4-a3cf-db43daad0c42`::Disk=20vda=20latency=20= not=20available=0AThread-1484341::DEBUG::2013-01-08=20= 10:41:09,559::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`9dc63ce4-0f76-4963-adfe-6f8eb1a44806`::Disk=20vda=20latency=20= not=20available=0AThread-1484341::DEBUG::2013-01-08=20= 10:41:09,559::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`e8683e88-f3f2-4fe9-80f7-f4888d8e7a13`::Disk=20vda=20latency=20= not=20available=0AThread-1484337::ERROR::2013-01-08=20= 10:41:09,754::vm::258::vm.Vm::(run)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Failed=20to=20migrate=0A= Traceback=20(most=20recent=20call=20last):=0A=20=20File=20= "/usr/share/vdsm/vm.py",=20line=20245,=20in=20run=0A=20=20=20=20= self._startUnderlyingMigration()=0A=20=20File=20= "/usr/share/vdsm/libvirtvm.py",=20line=20474,=20in=20= _startUnderlyingMigration=0A=20=20=20=20None,=20maxBandwidth)=0A=20=20= File=20"/usr/share/vdsm/libvirtvm.py",=20line=20510,=20in=20f=0A=20=20=20= =20ret=20=3D=20attr(*args,=20**kwargs)=0A=20=20File=20= "/usr/lib64/python2.6/site-packages/vdsm/libvirtconnection.py",=20line=20= 83,=20in=20wrapper=0A=20=20=20=20ret=20=3D=20f(*args,=20**kwargs)=0A=20=20= File=20"/usr/lib64/python2.6/site-packages/libvirt.py",=20line=201103,=20= in=20migrateToURI2=0A=20=20=20=20if=20ret=20=3D=3D=20-1:=20raise=20= libvirtError=20('virDomainMigrateToURI2()=20failed',=20dom=3Dself)=0A= libvirtError:=20internal=20error=20Process=20exited=20while=20reading=20= console=20log=20output:=20=0AThread-1484343::DEBUG::2013-01-08=20= 10:41:11,654::BindingXMLRPC::883::vds::(wrapper)=20client=20= [10.192.42.207]::call=20vmGetStats=20with=20= ('cfb17b98-1476-4fbf-9fab-7c7f48b60adf',)=20{}=0A= Thread-1484343::DEBUG::2013-01-08=20= 10:41:11,654::libvirtvm::278::vm.Vm::(_getDiskLatency)=20= vmId=3D`cfb17b98-1476-4fbf-9fab-7c7f48b60adf`::Disk=20vda=20latency=20= not=20available=0AThread-1484343::DEBUG::2013-01-08=20= 10:41:11,655::BindingXMLRPC::890::vds::(wrapper)=20return=20vmGetStats=20= with=20{'status':=20{'message':=20'Done',=20'code':=200},=20'statsList':=20= [{'status':=20'Up',=20'username':=20'Unknown',=20'memUsage':=20'0',=20= 'acpiEnable':=20'true',=20'pid':=20'5780',=20'displayIp':=20= '10.192.42.196',=20'displayPort':=20u'5904',=20'session':=20'Unknown',=20= 'displaySecurePort':=20u'5905',=20'timeOffset':=20-2L,=20'hash':=20= '-7242028114076316120',=20'balloonInfo':=20{'balloon_max':=201048576,=20= 'balloon_cur':=201048576},=20'pauseCode':=20'NOERR',=20'clientIp':=20'',=20= 'kvmEnable':=20'true',=20'network':=20{u'vnet2':=20{'macAddr':=20= '00:1a:4a:c0:2a:05',=20'rxDropped':=20'0',=20'rxErrors':=20'0',=20= 'txDropped':=20'0',=20'txRate':=20'0.0',=20'rxRate':=20'0.0',=20= 'txErrors':=20'0',=20'state':=20'unknown',=20'speed':=20'1000',=20= 'name':=20u'vnet2'}},=20'vmId':=20= 'cfb17b98-1476-4fbf-9fab-7c7f48b60adf',=20'displayType':=20'qxl',=20= 'cpuUser':=20'0.49',=20'disks':=20{u'vda':=20{'readRate':=20'0.00',=20= 'truesize':=20'2936401920',=20'apparentsize':=20'21474836480',=20= 'writeRate':=20'1273.87',=20'imageID':=20= '12a6472f-9ab9-436a-8508-b6721cb2b227'},=20u'hdc':=20{'readLatency':=20= '0',=20'apparentsize':=20'0',=20'writeLatency':=20'0',=20'flushLatency':=20= '0',=20'readRate':=20'0.00',=20'truesize':=20'0',=20'writeRate':=20= '0.00'}},=20'monitorResponse':=20'0',=20'statsAge':=20'0.36',=20= 'elapsedTime':=20'1707880',=20'vmType':=20'kvm',=20'cpuSys':=20'0.40',=20= 'appsList':=20[],=20'guestIPs':=20''}]}=0A= Thread-1484344::DEBUG::2013-01-08=20= 10:41:11,679::BindingXMLRPC::883::vds::(wrapper)=20client=20= [10.192.42.207]::call=20vmGetMigrationStatus=20with=20= ('cfb17b98-1476-4fbf-9fab-7c7f48b60adf',)=20{}=0A= Thread-1484344::DEBUG::2013-01-08=20= 10:41:11,679::BindingXMLRPC::890::vds::(wrapper)=20return=20= vmGetMigrationStatus=20with=20{'status':=20{'message':=20'Fatal=20error=20= during=20migration',=20'code':=2012}}=0AVM=20Channels=20= Listener::DEBUG::2013-01-08=20= 10:41:12,057::vmChannels::60::vds::(_handle_timeouts)=20Timeout=20on=20= fileno=2018.=0A= --Apple-Mail=_C82D1F4B-8BD2-4AB4-8D7E-7DAE9D5676F1 Content-Transfer-Encoding: 7bit Content-Type: text/plain; charset=iso-8859-1 log from both sides. see attached --Apple-Mail=_C82D1F4B-8BD2-4AB4-8D7E-7DAE9D5676F1--