<div class="gmail_quote">---------- Forwarded message ----------<br>From: "Koen Vanoppen" <<a href="mailto:vanoppen.koen@gmail.com">vanoppen.koen@gmail.com</a>><br>Date: Feb 28, 2014 8:08 AM<br>Subject: Re: [Users] Migration Failed<br>
To: "Dan Kenigsberg" <<a href="mailto:danken@redhat.com">danken@redhat.com</a>><br>Cc: <br><br type="attribution"><div dir="ltr"><div><div>Never mind... God damned... :-D<br><br></div>Found the error. Apparently with the move to fibre, someone put a hosts entry in the /etc/hosts file for buran... To this wrong IP... Thanks again everyone for the support. It did help me to find the solution.<br>
<br></div>Thanks!!<br></div><div class="gmail_extra"><br><br><div class="gmail_quote">2014-02-28 8:03 GMT+01:00 Koen Vanoppen <span dir="ltr"><<a href="mailto:vanoppen.koen@gmail.com" target="_blank">vanoppen.koen@gmail.com</a>></span>:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div><div>The ping I receive on hostX to buran is the IP from the previous iscsi address. But due some network issues we moved everything from iscsi to fibre... But how can it be they still receive the ip from the iscsi?<br>
<br></div>Kind regards,<br><br></div>Koen<br></div><div class="gmail_extra"><br><br><div class="gmail_quote">2014-02-28 7:55 GMT+01:00 Koen Vanoppen <span dir="ltr"><<a href="mailto:vanoppen.koen@gmail.com" target="_blank">vanoppen.koen@gmail.com</a>></span>:<div>
<div><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr">But moving from buran to soyuz does work. But as you can see in the logs, moving from X to buran, doesn't...<br>
</div><div class="gmail_extra"><br><br><div class="gmail_quote">2014-02-28 7:51 GMT+01:00 Koen Vanoppen <span dir="ltr"><<a href="mailto:vanoppen.koen@gmail.com" target="_blank">vanoppen.koen@gmail.com</a>></span>:<div>
<div><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div><div><div>Sorry for the late answers btw... Busy days here...<br><br></div>This is the result from the ping:<br>
>From <a href="http://buran.brusselsairport.aero" target="_blank">buran.brusselsairport.aero</a> (10.110.X.X) icmp_seq=1 Destination Host Prohibited<br>
>From <a href="http://buran.brusselsairport.aero" target="_blank">buran.brusselsairport.aero</a> (10.110.X.X) icmp_seq=2 Destination Host Prohibited<br><br></div>If I ping from one of the hypervisors i get a totaly different ip... and of course a host unreachable. Would it be better if I would just remove the host and re-add it?<br>
<br>Kind regards,<br><br></div>Koen<br></div><div class="gmail_extra"><br><br><div class="gmail_quote">2014-02-28 7:48 GMT+01:00 Koen Vanoppen <span dir="ltr"><<a href="mailto:vanoppen.koen@gmail.com" target="_blank">vanoppen.koen@gmail.com</a>></span>:<div>
<div><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div>Ok, here is the vdsm log output. He claims he has no route to the host... But why? They are both in the same data center... (Moving VM from soyuz --> buran):<br>
<br>Thread-1062381::DEBUG::2014-02-28 07:45:44,236::BindingXMLRPC::981::vds::(wrapper) return vmMigrate with {'status': {'message': 'Migration in progress', 'code': 0}, 'progress': 0}<br>
Thread-1062382::DEBUG::2014-02-28 07:45:44,236::vm::180::vm.Vm::(_setupVdsConnection) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::Destination server is: <a href="http://buran.brusselsairport.aero:54321" target="_blank">buran.brusselsairport.aero:54321</a><br>
Thread-1062382::DEBUG::2014-02-28 07:45:44,237::vm::182::vm.Vm::(_setupVdsConnection) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::Initiating connection with destination<br>Dummy-223::DEBUG::2014-02-28 07:45:44,581::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) 'dd if=/rhev/data-center/cbbb821c-0523-4639-b453-9371ff5dc7b1/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=1024000' (cwd None)<br>
Dummy-223::DEBUG::2014-02-28 07:45:44,717::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) SUCCESS: <err> = '1+0 records in\n1+0 records out\n1024000 bytes (1.0 MB) copied, 0.046894 s, 21.8 MB/s\n'; <rc> = 0<br>
Thread-1062383::DEBUG::2014-02-28 07:45:44,877::BindingXMLRPC::177::vds::(wrapper) client [10.110.57.30]<br>Thread-1062383::DEBUG::2014-02-28 07:45:44,884::task::579::TaskManager.Task::(_updateState) Task=`9e1e642a-2dfb-4519-8f98-f98bb520fc5e`::moving from state init -> state preparing<br>
Thread-1062383::INFO::2014-02-28 07:45:44,887::logUtils::44::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', options=None)<br>Thread-1062383::INFO::2014-02-28 07:45:44,888::logUtils::47::dispatcher::(wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 2, 'spmStatus': 'SPM', 'spmLver': 1}}<br>
Thread-1062383::DEBUG::2014-02-28 07:45:44,888::task::1168::TaskManager.Task::(prepare) Task=`9e1e642a-2dfb-4519-8f98-f98bb520fc5e`::finished: {'spm_st': {'spmId': 2, 'spmStatus': 'SPM', 'spmLver': 1}}<br>
Thread-1062383::DEBUG::2014-02-28 07:45:44,888::task::579::TaskManager.Task::(_updateState) Task=`9e1e642a-2dfb-4519-8f98-f98bb520fc5e`::moving from state preparing -> state finished<br>Thread-1062383::DEBUG::2014-02-28 07:45:44,889::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-1062383::DEBUG::2014-02-28 07:45:44,889::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-1062383::DEBUG::2014-02-28 07:45:44,889::task::974::TaskManager.Task::(_decref) Task=`9e1e642a-2dfb-4519-8f98-f98bb520fc5e`::ref 0 aborting False<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,895::BindingXMLRPC::177::vds::(wrapper) client [10.110.57.30]<br>Thread-1062384::DEBUG::2014-02-28 07:45:44,895::task::579::TaskManager.Task::(_updateState) Task=`8862a9b6-a0da-440f-858f-3aec8df12e3f`::moving from state init -> state preparing<br>
Thread-1062384::INFO::2014-02-28 07:45:44,896::logUtils::44::dispatcher::(wrapper) Run and protect: getStoragePoolInfo(spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', options=None)<br>Thread-1062384::DEBUG::2014-02-28 07:45:44,897::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1`ReqID=`30e9c6a5-9be6-42c3-ac2a-1159a5619995`::Request was made in '/usr/share/vdsm/storage/hsm.py' line '2552' at 'getStoragePoolInfo'<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,897::resourceManager::541::ResourceManager::(registerResource) Trying to register resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' for lock type 'shared'<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,898::resourceManager::600::ResourceManager::(registerResource) Resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' is free. Now locking as 'shared' (1 active user)<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,898::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1`ReqID=`30e9c6a5-9be6-42c3-ac2a-1159a5619995`::Granted request<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,899::task::811::TaskManager.Task::(resourceAcquired) Task=`8862a9b6-a0da-440f-858f-3aec8df12e3f`::_resourcesAcquired: Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1 (shared)<br>Thread-1062384::DEBUG::2014-02-28 07:45:44,899::task::974::TaskManager.Task::(_decref) Task=`8862a9b6-a0da-440f-858f-3aec8df12e3f`::ref 1 aborting False<br>
Thread-1062384::INFO::2014-02-28 07:45:44,901::logUtils::47::dispatcher::(wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_id': 2, 'master_uuid': '5d2c10af-23bb-4112-9d05-d0c4e65f80ef', 'name': 'TestDev', 'version': '3', 'domains': u'1988ca23-e1ac-4831-bc57-768d01b0f1d1:Active,5d2c10af-23bb-4112-9d05-d0c4e65f80ef:Active,983782c7-e157-43e4-a3a3-b84d64530b96:Active,50cf24a4-d1ef-4105-a9a5-b81d91339175:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/mnt/vega.brusselsairport.aero:_var_lib_exports_iso/50cf24a4-d1ef-4105-a9a5-b81d91339175/images/11111111-1111-1111-1111-111111111111', 'type': 'FCP', 'master_ver': 1, 'lver': 1}, 'dominfo': {u'1988ca23-e1ac-4831-bc57-768d01b0f1d1': {'status': u'Active', 'diskfree': '300379275264', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'5d2c10af-23bb-4112-9d05-d0c4e65f80ef': {'status': u'Active', 'diskfree': '69524783104', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'983782c7-e157-43e4-a3a3-b84d64530b96': {'status': u'Active', 'diskfree': '169114337280', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'50cf24a4-d1ef-4105-a9a5-b81d91339175': {'status': u'Active', 'diskfree': '7559184384', 'alerts': [], 'version': 0, 'disktotal': '21891121152'}}}<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,901::task::1168::TaskManager.Task::(prepare) Task=`8862a9b6-a0da-440f-858f-3aec8df12e3f`::finished: {'info': {'spm_id': 2, 'master_uuid': '5d2c10af-23bb-4112-9d05-d0c4e65f80ef', 'name': 'TestDev', 'version': '3', 'domains': u'1988ca23-e1ac-4831-bc57-768d01b0f1d1:Active,5d2c10af-23bb-4112-9d05-d0c4e65f80ef:Active,983782c7-e157-43e4-a3a3-b84d64530b96:Active,50cf24a4-d1ef-4105-a9a5-b81d91339175:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/mnt/vega.brusselsairport.aero:_var_lib_exports_iso/50cf24a4-d1ef-4105-a9a5-b81d91339175/images/11111111-1111-1111-1111-111111111111', 'type': 'FCP', 'master_ver': 1, 'lver': 1}, 'dominfo': {u'1988ca23-e1ac-4831-bc57-768d01b0f1d1': {'status': u'Active', 'diskfree': '300379275264', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'5d2c10af-23bb-4112-9d05-d0c4e65f80ef': {'status': u'Active', 'diskfree': '69524783104', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'983782c7-e157-43e4-a3a3-b84d64530b96': {'status': u'Active', 'diskfree': '169114337280', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'50cf24a4-d1ef-4105-a9a5-b81d91339175': {'status': u'Active', 'diskfree': '7559184384', 'alerts': [], 'version': 0, 'disktotal': '21891121152'}}}<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,902::task::579::TaskManager.Task::(_updateState) Task=`8862a9b6-a0da-440f-858f-3aec8df12e3f`::moving from state preparing -> state finished<br>Thread-1062384::DEBUG::2014-02-28 07:45:44,902::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1': < ResourceRef 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1', isValid: 'True' obj: 'None'>}<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,903::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-1062384::DEBUG::2014-02-28 07:45:44,903::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1'<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,904::resourceManager::634::ResourceManager::(releaseResource) Released resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' (0 active users)<br>Thread-1062384::DEBUG::2014-02-28 07:45:44,904::resourceManager::640::ResourceManager::(releaseResource) Resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' is free, finding out if anyone is waiting for it.<br>
Thread-1062384::DEBUG::2014-02-28 07:45:44,904::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1', Clearing records.<br>Thread-1062384::DEBUG::2014-02-28 07:45:44,905::task::974::TaskManager.Task::(_decref) Task=`8862a9b6-a0da-440f-858f-3aec8df12e3f`::ref 0 aborting False<br>
Thread-1062385::DEBUG::2014-02-28 07:45:45,852::task::579::TaskManager.Task::(_updateState) Task=`2dc97e57-449e-4335-ba9b-c69d22e968d4`::moving from state init -> state preparing<br>Thread-1062385::INFO::2014-02-28 07:45:45,852::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)<br>
Thread-1062385::INFO::2014-02-28 07:45:45,853::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {u'1988ca23-e1ac-4831-bc57-768d01b0f1d1': {'delay': '0.000139105', 'lastCheck': '6.2', 'code': 0, 'valid': True, 'version': 3}, '5d2c10af-23bb-4112-9d05-d0c4e65f80ef': {'delay': '0.000157599', 'lastCheck': '4.6', 'code': 0, 'valid': True, 'version': 3}, u'983782c7-e157-43e4-a3a3-b84d64530b96': {'delay': '0.000136455', 'lastCheck': '4.3', 'code': 0, 'valid': True, 'version': 3}, '50cf24a4-d1ef-4105-a9a5-b81d91339175': {'delay': '0.000406695', 'lastCheck': '5.3', 'code': 0, 'valid': True, 'version': 0}}<br>
Thread-1062385::DEBUG::2014-02-28 07:45:45,853::task::1168::TaskManager.Task::(prepare) Task=`2dc97e57-449e-4335-ba9b-c69d22e968d4`::finished: {u'1988ca23-e1ac-4831-bc57-768d01b0f1d1': {'delay': '0.000139105', 'lastCheck': '6.2', 'code': 0, 'valid': True, 'version': 3}, '5d2c10af-23bb-4112-9d05-d0c4e65f80ef': {'delay': '0.000157599', 'lastCheck': '4.6', 'code': 0, 'valid': True, 'version': 3}, u'983782c7-e157-43e4-a3a3-b84d64530b96': {'delay': '0.000136455', 'lastCheck': '4.3', 'code': 0, 'valid': True, 'version': 3}, '50cf24a4-d1ef-4105-a9a5-b81d91339175': {'delay': '0.000406695', 'lastCheck': '5.3', 'code': 0, 'valid': True, 'version': 0}}<br>
Thread-1062385::DEBUG::2014-02-28 07:45:45,854::task::579::TaskManager.Task::(_updateState) Task=`2dc97e57-449e-4335-ba9b-c69d22e968d4`::moving from state preparing -> state finished<br>Thread-1062385::DEBUG::2014-02-28 07:45:45,854::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-1062385::DEBUG::2014-02-28 07:45:45,855::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-1062385::DEBUG::2014-02-28 07:45:45,855::task::974::TaskManager.Task::(_decref) Task=`2dc97e57-449e-4335-ba9b-c69d22e968d4`::ref 0 aborting False<br>
Thread-914330::DEBUG::2014-02-28 07:45:45,984::task::579::TaskManager.Task::(_updateState) Task=`b67d671f-340a-4a53-9532-aed8e8b1456e`::moving from state init -> state preparing<br>Thread-914330::INFO::2014-02-28 07:45:45,987::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='5d2c10af-23bb-4112-9d05-d0c4e65f80ef', spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', imgUUID='64df4085-2d8d-4c30-b983-a0049fa7db03', volUUID='64a8ee5d-b847-4e6d-97fc-9390b8da9ede', options=None)<br>
Thread-914330::INFO::2014-02-28 07:45:45,991::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '1073741824', 'apparentsize': '1073741824'}<br>
Thread-914330::DEBUG::2014-02-28 07:45:45,992::task::1168::TaskManager.Task::(prepare) Task=`b67d671f-340a-4a53-9532-aed8e8b1456e`::finished: {'truesize': '1073741824', 'apparentsize': '1073741824'}<br>
Thread-914330::DEBUG::2014-02-28 07:45:45,993::task::579::TaskManager.Task::(_updateState) Task=`b67d671f-340a-4a53-9532-aed8e8b1456e`::moving from state preparing -> state finished<br>Thread-914330::DEBUG::2014-02-28 07:45:45,995::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-914330::DEBUG::2014-02-28 07:45:45,996::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-914330::DEBUG::2014-02-28 07:45:45,997::task::974::TaskManager.Task::(_decref) Task=`b67d671f-340a-4a53-9532-aed8e8b1456e`::ref 0 aborting False<br>
Dummy-223::DEBUG::2014-02-28 07:45:46,749::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) 'dd if=/rhev/data-center/cbbb821c-0523-4639-b453-9371ff5dc7b1/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=1024000' (cwd None)<br>
Dummy-223::DEBUG::2014-02-28 07:45:46,905::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) SUCCESS: <err> = '1+0 records in\n1+0 records out\n1024000 bytes (1.0 MB) copied, 0.0441819 s, 23.2 MB/s\n'; <rc> = 0<br>
Thread-1062382::ERROR::2014-02-28 07:45:47,240::vm::188::vm.Vm::(_setupVdsConnection) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::Error initiating connection<br>Traceback (most recent call last):<br> File "/usr/share/vdsm/vm.py", line 183, in _setupVdsConnection<br>
status = self.destServer.getVmStats(self._<a href="http://vm.id" target="_blank">vm.id</a>)<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1199, in __call__<br> return self.__send(self.__name, args)<br>
File "/usr/lib64/python2.6/xmlrpclib.py", line 1489, in __request<br>
verbose=self.__verbose<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1235, in request<br> self.send_content(h, request_body)<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1349, in send_content<br>
connection.endheaders()<br> File "/usr/lib64/python2.6/httplib.py", line 908, in endheaders<br> self._send_output()<br> File "/usr/lib64/python2.6/httplib.py", line 780, in _send_output<br> self.send(msg)<br>
File "/usr/lib64/python2.6/httplib.py", line 739, in send<br> self.connect()<br> File "/usr/share/vdsm/kaxmlrpclib.py", line 151, in connect<br> SecureXMLRPCServer.VerifyingHTTPSConnection.connect(self)<br>
File "/usr/lib64/python2.6/site-packages/vdsm/SecureXMLRPCServer.py", line 188, in connect<br> sock = socket.create_connection((self.host, self.port), self.timeout)<br> File "/usr/lib64/python2.6/socket.py", line 567, in create_connection<br>
raise error, msg<br>error: [Errno 113] No route to host<br>Thread-1062382::DEBUG::2014-02-28 07:45:47,245::vm::232::vm.Vm::(_prepareGuest) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::Migration started<br>Thread-1062382::DEBUG::2014-02-28 07:45:47,274::vm::314::vm.Vm::(run) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::migration semaphore acquired<br>
Dummy-223::DEBUG::2014-02-28 07:45:48,928::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) 'dd if=/rhev/data-center/cbbb821c-0523-4639-b453-9371ff5dc7b1/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=1024000' (cwd None)<br>
Thread-5543::DEBUG::2014-02-28 07:45:49,662::blockSD::595::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/dev/1988ca23-e1ac-4831-bc57-768d01b0f1d1/metadata bs=4096 count=1' (cwd None)<br>Thread-5543::DEBUG::2014-02-28 07:45:49,726::blockSD::595::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.000179751 s, 22.8 MB/s\n'; <rc> = 0<br>
Dummy-223::DEBUG::2014-02-28 07:45:49,779::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) SUCCESS: <err> = '1+0 records in\n1+0 records out\n1024000 bytes (1.0 MB) copied, 0.479458 s, 2.1 MB/s\n'; <rc> = 0<br>
Thread-1062382::ERROR::2014-02-28 07:45:50,240::vm::238::vm.Vm::(_recover) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::[Errno 113] No route to host<br>Thread-623::DEBUG::2014-02-28 07:45:50,545::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/vega.brusselsairport.aero:_var_lib_exports_iso/50cf24a4-d1ef-4105-a9a5-b81d91339175/dom_md/metadata bs=4096 count=1' (cwd None)<br>
Thread-623::DEBUG::2014-02-28 07:45:50,573::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n479 bytes (479 B) copied, 0.000479018 s, 1000 kB/s\n'; <rc> = 0<br>
Thread-677219::DEBUG::2014-02-28 07:45:50,748::task::579::TaskManager.Task::(_updateState) Task=`2d1201c9-6c29-40ce-aaf6-5ec52be3f5dd`::moving from state init -> state preparing<br>Thread-677219::INFO::2014-02-28 07:45:50,748::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='5d2c10af-23bb-4112-9d05-d0c4e65f80ef', spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', imgUUID='6e17acdd-4cc0-4903-86c3-e354a5020436', volUUID='4b6a6646-7fec-42bd-a6be-798f54f29e6f', options=None)<br>
Thread-677219::INFO::2014-02-28 07:45:50,749::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '5368709120', 'apparentsize': '5368709120'}<br>
Thread-677219::DEBUG::2014-02-28 07:45:50,750::task::1168::TaskManager.Task::(prepare) Task=`2d1201c9-6c29-40ce-aaf6-5ec52be3f5dd`::finished: {'truesize': '5368709120', 'apparentsize': '5368709120'}<br>
Thread-677219::DEBUG::2014-02-28 07:45:50,750::task::579::TaskManager.Task::(_updateState) Task=`2d1201c9-6c29-40ce-aaf6-5ec52be3f5dd`::moving from state preparing -> state finished<br>Thread-677219::DEBUG::2014-02-28 07:45:50,750::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-677219::DEBUG::2014-02-28 07:45:50,751::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-677219::DEBUG::2014-02-28 07:45:50,751::task::974::TaskManager.Task::(_decref) Task=`2d1201c9-6c29-40ce-aaf6-5ec52be3f5dd`::ref 0 aborting False<br>
Thread-505346::DEBUG::2014-02-28 07:45:50,971::task::579::TaskManager.Task::(_updateState) Task=`a88bfc16-9821-442e-b563-21b7c60578a2`::moving from state init -> state preparing<br>Thread-505346::INFO::2014-02-28 07:45:50,972::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='5d2c10af-23bb-4112-9d05-d0c4e65f80ef', spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', imgUUID='d06c7100-64b5-481e-a620-73a91c8f8972', volUUID='93fb3163-2098-47e0-9069-69c047a87ae8', options=None)<br>
Thread-505346::INFO::2014-02-28 07:45:50,972::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '5368709120', 'apparentsize': '5368709120'}<br>
Thread-505346::DEBUG::2014-02-28 07:45:50,973::task::1168::TaskManager.Task::(prepare) Task=`a88bfc16-9821-442e-b563-21b7c60578a2`::finished: {'truesize': '5368709120', 'apparentsize': '5368709120'}<br>
Thread-505346::DEBUG::2014-02-28 07:45:50,973::task::579::TaskManager.Task::(_updateState) Task=`a88bfc16-9821-442e-b563-21b7c60578a2`::moving from state preparing -> state finished<br>Thread-505346::DEBUG::2014-02-28 07:45:50,973::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-505346::DEBUG::2014-02-28 07:45:50,974::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-505346::DEBUG::2014-02-28 07:45:50,974::task::974::TaskManager.Task::(_decref) Task=`a88bfc16-9821-442e-b563-21b7c60578a2`::ref 0 aborting False<br>
Thread-147::DEBUG::2014-02-28 07:45:51,236::blockSD::595::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/dev/5d2c10af-23bb-4112-9d05-d0c4e65f80ef/metadata bs=4096 count=1' (cwd None)<br>Thread-147::DEBUG::2014-02-28 07:45:51,269::blockSD::595::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.000178573 s, 22.9 MB/s\n'; <rc> = 0<br>
Thread-4123::DEBUG::2014-02-28 07:45:51,524::blockSD::595::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/dev/983782c7-e157-43e4-a3a3-b84d64530b96/metadata bs=4096 count=1' (cwd None)<br>Thread-4123::DEBUG::2014-02-28 07:45:51,556::blockSD::595::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.00017962 s, 22.8 MB/s\n'; <rc> = 0<br>
Thread-145024::DEBUG::2014-02-28 07:45:51,566::task::579::TaskManager.Task::(_updateState) Task=`952ea2ea-d189-4c2a-a56b-2c5339cff1e0`::moving from state init -> state preparing<br>Thread-145024::INFO::2014-02-28 07:45:51,566::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='5d2c10af-23bb-4112-9d05-d0c4e65f80ef', spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', imgUUID='e7e25a1c-3d8b-4baf-8f14-5b76b8b4181d', volUUID='47eb08e4-747b-4ec2-a3fd-12bf91a1d3be', options=None)<br>
Thread-145024::INFO::2014-02-28 07:45:51,567::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '5368709120', 'apparentsize': '5368709120'}<br>
Thread-145024::DEBUG::2014-02-28 07:45:51,567::task::1168::TaskManager.Task::(prepare) Task=`952ea2ea-d189-4c2a-a56b-2c5339cff1e0`::finished: {'truesize': '5368709120', 'apparentsize': '5368709120'}<br>
Thread-145024::DEBUG::2014-02-28 07:45:51,567::task::579::TaskManager.Task::(_updateState) Task=`952ea2ea-d189-4c2a-a56b-2c5339cff1e0`::moving from state preparing -> state finished<br>Thread-145024::DEBUG::2014-02-28 07:45:51,568::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-145024::DEBUG::2014-02-28 07:45:51,568::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-145024::DEBUG::2014-02-28 07:45:51,568::task::974::TaskManager.Task::(_decref) Task=`952ea2ea-d189-4c2a-a56b-2c5339cff1e0`::ref 0 aborting False<br>
Dummy-223::DEBUG::2014-02-28 07:45:51,798::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) 'dd if=/rhev/data-center/cbbb821c-0523-4639-b453-9371ff5dc7b1/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=1024000' (cwd None)<br>
Thread-144577::DEBUG::2014-02-28 07:45:51,897::task::579::TaskManager.Task::(_updateState) Task=`0aefb5af-15b3-49c0-a9b1-88f78d4b916d`::moving from state init -> state preparing<br>Thread-144577::INFO::2014-02-28 07:45:51,925::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='983782c7-e157-43e4-a3a3-b84d64530b96', spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', imgUUID='915a06d6-ae09-4410-be2b-c9c4db4dda7c', volUUID='b199f071-8e45-4254-99e7-3b9212c293f2', options=None)<br>
Thread-144577::INFO::2014-02-28 07:45:51,933::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '21474836480', 'apparentsize': '21474836480'}<br>
Thread-144577::DEBUG::2014-02-28 07:45:51,937::task::1168::TaskManager.Task::(prepare) Task=`0aefb5af-15b3-49c0-a9b1-88f78d4b916d`::finished: {'truesize': '21474836480', 'apparentsize': '21474836480'}<br>
Thread-144577::DEBUG::2014-02-28 07:45:51,942::task::579::TaskManager.Task::(_updateState) Task=`0aefb5af-15b3-49c0-a9b1-88f78d4b916d`::moving from state preparing -> state finished<br>Thread-144577::DEBUG::2014-02-28 07:45:51,943::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-144577::DEBUG::2014-02-28 07:45:51,945::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-144577::DEBUG::2014-02-28 07:45:51,946::task::974::TaskManager.Task::(_decref) Task=`0aefb5af-15b3-49c0-a9b1-88f78d4b916d`::ref 0 aborting False<br>
Dummy-223::DEBUG::2014-02-28 07:45:52,005::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) SUCCESS: <err> = '1+0 records in\n1+0 records out\n1024000 bytes (1.0 MB) copied, 0.0611125 s, 16.8 MB/s\n'; <rc> = 0<br>
Thread-1062382::ERROR::2014-02-28 07:45:53,241::vm::243::vm.Vm::(_recover) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::Failed to destroy remote VM<br>Traceback (most recent call last):<br> File "/usr/share/vdsm/vm.py", line 241, in _recover<br>
self.destServer.destroy(self._<a href="http://vm.id" target="_blank">vm.id</a>)<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1199, in __call__<br> return self.__send(self.__name, args)<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1489, in __request<br>
verbose=self.__verbose<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1235, in request<br> self.send_content(h, request_body)<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1349, in send_content<br>
connection.endheaders()<br> File "/usr/lib64/python2.6/httplib.py", line 908, in endheaders<br> self._send_output()<br> File "/usr/lib64/python2.6/httplib.py", line 780, in _send_output<br> self.send(msg)<br>
File "/usr/lib64/python2.6/httplib.py", line 739, in send<br> self.connect()<br> File "/usr/share/vdsm/kaxmlrpclib.py", line 151, in connect<br> SecureXMLRPCServer.VerifyingHTTPSConnection.connect(self)<br>
File "/usr/lib64/python2.6/site-packages/vdsm/SecureXMLRPCServer.py", line 188, in connect<br> sock = socket.create_connection((self.host, self.port), self.timeout)<br> File "/usr/lib64/python2.6/socket.py", line 567, in create_connection<br>
raise error, msg<br>error: [Errno 113] No route to host<br>Thread-1062382::ERROR::2014-02-28 07:45:53,272::vm::337::vm.Vm::(run) vmId=`a3f94aee-e224-4fd8-8448-fc48017088da`::Failed to migrate<br>Traceback (most recent call last):<br>
File "/usr/share/vdsm/vm.py", line 323, in run<br> self._startUnderlyingMigration()<br> File "/usr/share/vdsm/vm.py", line 355, in _startUnderlyingMigration<br> response = self.destServer.migrationCreate(self._machineParams)<br>
File "/usr/lib64/python2.6/xmlrpclib.py", line 1199, in __call__<br> return self.__send(self.__name, args)<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1489, in __request<br> verbose=self.__verbose<br>
File "/usr/lib64/python2.6/xmlrpclib.py", line 1235, in request<br> self.send_content(h, request_body)<br> File "/usr/lib64/python2.6/xmlrpclib.py", line 1349, in send_content<br> connection.endheaders()<br>
File "/usr/lib64/python2.6/httplib.py", line 908, in endheaders<br> self._send_output()<br> File "/usr/lib64/python2.6/httplib.py", line 780, in _send_output<br> self.send(msg)<br> File "/usr/lib64/python2.6/httplib.py", line 739, in send<br>
self.connect()<br> File "/usr/share/vdsm/kaxmlrpclib.py", line 151, in connect<br> SecureXMLRPCServer.VerifyingHTTPSConnection.connect(self)<br> File "/usr/lib64/python2.6/site-packages/vdsm/SecureXMLRPCServer.py", line 188, in connect<br>
sock = socket.create_connection((self.host, self.port), self.timeout)<br> File "/usr/lib64/python2.6/socket.py", line 567, in create_connection<br> raise error, msg<br>error: [Errno 113] No route to host<br>
Thread-143613::DEBUG::2014-02-28 07:45:53,639::task::579::TaskManager.Task::(_updateState) Task=`9aff0027-f758-4474-930c-6459f5f14a4e`::moving from state init -> state preparing<br>Thread-145283::DEBUG::2014-02-28 07:45:53,643::task::579::TaskManager.Task::(_updateState) Task=`470100ed-def6-4d7a-9f08-24862d534e0f`::moving from state init -> state preparing<br>
Thread-143613::INFO::2014-02-28 07:45:53,652::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='1988ca23-e1ac-4831-bc57-768d01b0f1d1', spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', imgUUID='30a5b214-e7eb-4a98-8c4f-3f5c5f17ee90', volUUID='e1fb7e46-afb7-429a-9fac-7b4dcdc30151', options=None)<br>
Thread-145283::INFO::2014-02-28 07:45:53,654::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='5d2c10af-23bb-4112-9d05-d0c4e65f80ef', spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', imgUUID='abcec233-bfe5-48c9-876d-23725010b547', volUUID='44d0fb51-d5d5-4952-a55e-ae1ff5fdc4c4', options=None)<br>
Thread-143613::INFO::2014-02-28 07:45:53,663::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '21474836480', 'apparentsize': '21474836480'}<br>
Thread-145283::INFO::2014-02-28 07:45:53,664::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '10737418240', 'apparentsize': '10737418240'}<br>
Thread-143613::DEBUG::2014-02-28 07:45:53,669::task::1168::TaskManager.Task::(prepare) Task=`9aff0027-f758-4474-930c-6459f5f14a4e`::finished: {'truesize': '21474836480', 'apparentsize': '21474836480'}<br>
Thread-145283::DEBUG::2014-02-28 07:45:53,671::task::1168::TaskManager.Task::(prepare) Task=`470100ed-def6-4d7a-9f08-24862d534e0f`::finished: {'truesize': '10737418240', 'apparentsize': '10737418240'}<br>
Thread-143613::DEBUG::2014-02-28 07:45:53,674::task::579::TaskManager.Task::(_updateState) Task=`9aff0027-f758-4474-930c-6459f5f14a4e`::moving from state preparing -> state finished<br>Thread-145283::DEBUG::2014-02-28 07:45:53,675::task::579::TaskManager.Task::(_updateState) Task=`470100ed-def6-4d7a-9f08-24862d534e0f`::moving from state preparing -> state finished<br>
Thread-143613::DEBUG::2014-02-28 07:45:53,677::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>Thread-145283::DEBUG::2014-02-28 07:45:53,678::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-143613::DEBUG::2014-02-28 07:45:53,679::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-145283::DEBUG::2014-02-28 07:45:53,680::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>
Thread-143613::DEBUG::2014-02-28 07:45:53,682::task::974::TaskManager.Task::(_decref) Task=`9aff0027-f758-4474-930c-6459f5f14a4e`::ref 0 aborting False<br>Thread-145283::DEBUG::2014-02-28 07:45:53,683::task::974::TaskManager.Task::(_decref) Task=`470100ed-def6-4d7a-9f08-24862d534e0f`::ref 0 aborting False<br>
Dummy-223::DEBUG::2014-02-28 07:45:54,027::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) 'dd if=/rhev/data-center/cbbb821c-0523-4639-b453-9371ff5dc7b1/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=1024000' (cwd None)<br>
Dummy-223::DEBUG::2014-02-28 07:45:54,162::storage_mailbox::733::Storage.Misc.excCmd::(_checkForMail) SUCCESS: <err> = '1+0 records in\n1+0 records out\n1024000 bytes (1.0 MB) copied, 0.049987 s, 20.5 MB/s\n'; <rc> = 0<br>
Thread-1062389::DEBUG::2014-02-28 07:45:54,970::BindingXMLRPC::177::vds::(wrapper) client [10.110.57.30]<br>Thread-1062389::DEBUG::2014-02-28 07:45:54,971::task::579::TaskManager.Task::(_updateState) Task=`21158aca-9ec0-433c-9cbe-3d06c0e9db3e`::moving from state init -> state preparing<br>
Thread-1062389::INFO::2014-02-28 07:45:54,972::logUtils::44::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', options=None)<br>Thread-1062389::INFO::2014-02-28 07:45:54,972::logUtils::47::dispatcher::(wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 2, 'spmStatus': 'SPM', 'spmLver': 1}}<br>
Thread-1062389::DEBUG::2014-02-28 07:45:54,973::task::1168::TaskManager.Task::(prepare) Task=`21158aca-9ec0-433c-9cbe-3d06c0e9db3e`::finished: {'spm_st': {'spmId': 2, 'spmStatus': 'SPM', 'spmLver': 1}}<br>
Thread-1062389::DEBUG::2014-02-28 07:45:54,973::task::579::TaskManager.Task::(_updateState) Task=`21158aca-9ec0-433c-9cbe-3d06c0e9db3e`::moving from state preparing -> state finished<br>Thread-1062389::DEBUG::2014-02-28 07:45:54,973::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-1062389::DEBUG::2014-02-28 07:45:54,974::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-1062389::DEBUG::2014-02-28 07:45:54,974::task::974::TaskManager.Task::(_decref) Task=`21158aca-9ec0-433c-9cbe-3d06c0e9db3e`::ref 0 aborting False<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,979::BindingXMLRPC::177::vds::(wrapper) client [10.110.57.30]<br>Thread-1062390::DEBUG::2014-02-28 07:45:54,980::task::579::TaskManager.Task::(_updateState) Task=`7b2ebce0-748c-43d7-9b11-49e8edc744b3`::moving from state init -> state preparing<br>
Thread-1062390::INFO::2014-02-28 07:45:54,980::logUtils::44::dispatcher::(wrapper) Run and protect: getStoragePoolInfo(spUUID='cbbb821c-0523-4639-b453-9371ff5dc7b1', options=None)<br>Thread-1062390::DEBUG::2014-02-28 07:45:54,981::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1`ReqID=`b0243651-7244-4799-bbd4-163874d4f8f9`::Request was made in '/usr/share/vdsm/storage/hsm.py' line '2552' at 'getStoragePoolInfo'<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,981::resourceManager::541::ResourceManager::(registerResource) Trying to register resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' for lock type 'shared'<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,982::resourceManager::600::ResourceManager::(registerResource) Resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' is free. Now locking as 'shared' (1 active user)<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,982::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1`ReqID=`b0243651-7244-4799-bbd4-163874d4f8f9`::Granted request<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,983::task::811::TaskManager.Task::(resourceAcquired) Task=`7b2ebce0-748c-43d7-9b11-49e8edc744b3`::_resourcesAcquired: Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1 (shared)<br>Thread-1062390::DEBUG::2014-02-28 07:45:54,983::task::974::TaskManager.Task::(_decref) Task=`7b2ebce0-748c-43d7-9b11-49e8edc744b3`::ref 1 aborting False<br>
Thread-1062390::INFO::2014-02-28 07:45:54,985::logUtils::47::dispatcher::(wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_id': 2, 'master_uuid': '5d2c10af-23bb-4112-9d05-d0c4e65f80ef', 'name': 'TestDev', 'version': '3', 'domains': u'1988ca23-e1ac-4831-bc57-768d01b0f1d1:Active,5d2c10af-23bb-4112-9d05-d0c4e65f80ef:Active,983782c7-e157-43e4-a3a3-b84d64530b96:Active,50cf24a4-d1ef-4105-a9a5-b81d91339175:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/mnt/vega.brusselsairport.aero:_var_lib_exports_iso/50cf24a4-d1ef-4105-a9a5-b81d91339175/images/11111111-1111-1111-1111-111111111111', 'type': 'FCP', 'master_ver': 1, 'lver': 1}, 'dominfo': {u'1988ca23-e1ac-4831-bc57-768d01b0f1d1': {'status': u'Active', 'diskfree': '300379275264', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'5d2c10af-23bb-4112-9d05-d0c4e65f80ef': {'status': u'Active', 'diskfree': '69524783104', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'983782c7-e157-43e4-a3a3-b84d64530b96': {'status': u'Active', 'diskfree': '169114337280', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'50cf24a4-d1ef-4105-a9a5-b81d91339175': {'status': u'Active', 'diskfree': '7559184384', 'alerts': [], 'version': 0, 'disktotal': '21891121152'}}}<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,985::task::1168::TaskManager.Task::(prepare) Task=`7b2ebce0-748c-43d7-9b11-49e8edc744b3`::finished: {'info': {'spm_id': 2, 'master_uuid': '5d2c10af-23bb-4112-9d05-d0c4e65f80ef', 'name': 'TestDev', 'version': '3', 'domains': u'1988ca23-e1ac-4831-bc57-768d01b0f1d1:Active,5d2c10af-23bb-4112-9d05-d0c4e65f80ef:Active,983782c7-e157-43e4-a3a3-b84d64530b96:Active,50cf24a4-d1ef-4105-a9a5-b81d91339175:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/mnt/vega.brusselsairport.aero:_var_lib_exports_iso/50cf24a4-d1ef-4105-a9a5-b81d91339175/images/11111111-1111-1111-1111-111111111111', 'type': 'FCP', 'master_ver': 1, 'lver': 1}, 'dominfo': {u'1988ca23-e1ac-4831-bc57-768d01b0f1d1': {'status': u'Active', 'diskfree': '300379275264', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'5d2c10af-23bb-4112-9d05-d0c4e65f80ef': {'status': u'Active', 'diskfree': '69524783104', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'983782c7-e157-43e4-a3a3-b84d64530b96': {'status': u'Active', 'diskfree': '169114337280', 'alerts': [], 'version': 3, 'disktotal': '536468258816'}, u'50cf24a4-d1ef-4105-a9a5-b81d91339175': {'status': u'Active', 'diskfree': '7559184384', 'alerts': [], 'version': 0, 'disktotal': '21891121152'}}}<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,986::task::579::TaskManager.Task::(_updateState) Task=`7b2ebce0-748c-43d7-9b11-49e8edc744b3`::moving from state preparing -> state finished<br>Thread-1062390::DEBUG::2014-02-28 07:45:54,986::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1': < ResourceRef 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1', isValid: 'True' obj: 'None'>}<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,987::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-1062390::DEBUG::2014-02-28 07:45:54,987::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1'<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,987::resourceManager::634::ResourceManager::(releaseResource) Released resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' (0 active users)<br>Thread-1062390::DEBUG::2014-02-28 07:45:54,988::resourceManager::640::ResourceManager::(releaseResource) Resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1' is free, finding out if anyone is waiting for it.<br>
Thread-1062390::DEBUG::2014-02-28 07:45:54,988::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.cbbb821c-0523-4639-b453-9371ff5dc7b1', Clearing records.<br>Thread-1062390::DEBUG::2014-02-28 07:45:54,988::task::974::TaskManager.Task::(_decref) Task=`7b2ebce0-748c-43d7-9b11-49e8edc744b3`::ref 0 aborting False<br>
^C<br><br></div></div><div class="gmail_extra"><br><br><div class="gmail_quote">2014-02-27 15:38 GMT+01:00 Dan Kenigsberg <span dir="ltr"><<a href="mailto:danken@redhat.com" target="_blank">danken@redhat.com</a>></span>:<div>
<div><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div>On Thu, Feb 27, 2014 at 10:42:54AM +0100, Koen Vanoppen wrote:<br>
> Sorry...<br>
> I added the correct one now<br>
<br>
</div>Still, I fail to find the relevant ::ERROR:: line about migration.<br>
But as Michal mentioned, "Could not connect to peer VDS" means that<br>
source vdsm failed to contact the destination one.<br>
<br>
This can stem from physical or logical network problem.<br>
Can you ping from source to dest?<br>
Can what happens when you log into source host and run<br>
<br>
vdsClient -s fqdn-of-destination-host list<br>
<br>
? do you get any response? What happens if you disable your firewall?<br>
<br>
Regards,<br>
Dan.<br>
</blockquote></div></div></div><br></div>
</blockquote></div></div></div><br></div>
</blockquote></div></div></div><br></div>
</blockquote></div></div></div><br></div>
</blockquote></div><br></div>
</div>