[ https://issues.apache.org/jira/browse/CLOUDSTACK-4340?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Rayees Namathponnan closed CLOUDSTACK-4340. ------------------------------------------- Verified with latest run > [Automation] Test cases > TestVMLifeCycleStoppedVPCVR.test_07_migrate_instance_in_network failed during > migration > --------------------------------------------------------------------------------------------------------------- > > Key: CLOUDSTACK-4340 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4340 > Project: CloudStack > Issue Type: Test > Security Level: Public(Anyone can view this level - this is the > default.) > Components: API, Automation > Affects Versions: 4.2.0 > Environment: Automation > 4.2 > Reporter: Rayees Namathponnan > Assignee: Rayees Namathponnan > Fix For: 4.3.0 > > Attachments: management-server.rar > > > Test cases > integration.component.test_vpc_vm_life_cycle.TestVMLifeCycleStoppedVPCVR.test_07_migrate_instance_in_network > failed with latest build, failed during migration; > observed below error in automation log > ---------------------------------------------------------- > Failed to migrate instance, Execute cmd: asyncquery failed, due to: > {errorcode : 530, errortext : u'Cannot migrate VM, VM is already presnt on > this host, please specify valid destination host to migrate the VM'} > -------------------- >> begin captured logging << -------------------- > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Check the status of > VPC virtual router > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Checking if the host > is available for migration? > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Validating if the > network rules work properly or not? > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Checking if we can > SSH into VM_1 through 10.223.122.78? > paramiko.transport: DEBUG: starting thread (client mode): 0xad8f910L > paramiko.transport: INFO: Connected (version 2.0, client OpenSSH_4.3) > paramiko.transport: DEBUG: kex algos:['diffie-hellman-group-exchange-sha1', > 'diffie-hellman-group14-sha1', 'diffie-hellman-group1-sha1'] server > key:['ssh-rsa', 'ssh-dss'] client encrypt:['aes128-cbc', '3des-cbc', > 'blowfish-cbc', 'cast128-cbc', 'arcfour128', 'arcfour256', 'arcfour', > 'aes192-cbc', 'aes256-cbc', 'rijndael-...@lysator.liu.se', 'aes128-ctr', > 'aes192-ctr', 'aes256-ctr'] server encrypt:['aes128-cbc', '3des-cbc', > 'blowfish-cbc', 'cast128-cbc', 'arcfour128', 'arcfour256', 'arcfour', > 'aes192-cbc', 'aes256-cbc', 'rijndael-...@lysator.liu.se', 'aes128-ctr', > 'aes192-ctr', 'aes256-ctr'] client mac:['hmac-md5', 'hmac-sha1', > 'hmac-ripemd160', 'hmac-ripemd...@openssh.com', 'hmac-sha1-96', > 'hmac-md5-96'] server mac:['hmac-md5', 'hmac-sha1', 'hmac-ripemd160', > 'hmac-ripemd...@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] client > compress:['none', 'z...@openssh.com'] server compress:['none', > 'z...@openssh.com'] client lang:[''] server lang:[''] kex follows?False > paramiko.transport: DEBUG: Ciphers agreed: local=aes128-ctr, remote=aes128-ctr > paramiko.transport: DEBUG: using kex diffie-hellman-group1-sha1; server key > type ssh-rsa; cipher: local aes128-ctr, remote aes128-ctr; mac: local > hmac-sha1, remote hmac-sha1; compression: local none, remote none > paramiko.transport: DEBUG: Switch to new keys ... > paramiko.transport: DEBUG: Adding ssh-rsa host key for 10.223.122.78: > 0f8b3ff9dc4dce10340227dab3cac032 > paramiko.transport: DEBUG: Trying discovered key > 76be480fa6b8ad3b78082d6d19e4ee44 in /root/.ssh/id_rsa > paramiko.transport: DEBUG: userauth is OK > paramiko.transport: INFO: Authentication (publickey) failed. > paramiko.transport: DEBUG: userauth is OK > paramiko.transport: INFO: Authentication (password) successful! > sshClient: DEBUG: SSH connect: root@10.223.122.78 with passwd password > paramiko.transport: DEBUG: EOF in transport thread > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: SSH into VM is > successfully > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Verifying if we can > ping to outside world from VM? > paramiko.transport: DEBUG: [chan 1] Max packet in: 34816 bytes > paramiko.transport: DEBUG: [chan 1] Max packet out: 32768 bytes > paramiko.transport: INFO: Secsh channel 1 opened. > paramiko.transport: DEBUG: [chan 1] Sesch channel 1 request ok > paramiko.transport: DEBUG: [chan 1] EOF received (1) > sshClient: DEBUG: {Cmd: ping -c 1 www.google.com via Host: 10.223.122.78} > {returns: ['PING www.google.com (74.125.239.145) 56(84) bytes of data.', '64 > bytes from nuq05s02-in-f17.1e100.net (74.125.239.145): icmp_seq=1 ttl=48 > time=5.49 ms', '', '--- www.google.com ping statistics ---', '1 packets > transmitted, 1 received, 0% packet loss, time 0ms', 'rtt min/avg/max/mdev = > 5.497/5.497/5.497/0.000 ms']} > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Checking if we can > SSH into VM_1? > paramiko.transport: DEBUG: [chan 1] EOF sent (1) > paramiko.transport: DEBUG: starting thread (client mode): 0xad8f590L > paramiko.transport: INFO: Connected (version 2.0, client OpenSSH_4.3) > paramiko.transport: DEBUG: kex algos:['diffie-hellman-group-exchange-sha1', > 'diffie-hellman-group14-sha1', 'diffie-hellman-group1-sha1'] server > key:['ssh-rsa', 'ssh-dss'] client encrypt:['aes128-cbc', '3des-cbc', > 'blowfish-cbc', 'cast128-cbc', 'arcfour128', 'arcfour256', 'arcfour', > 'aes192-cbc', 'aes256-cbc', 'rijndael-...@lysator.liu.se', 'aes128-ctr', > 'aes192-ctr', 'aes256-ctr'] server encrypt:['aes128-cbc', '3des-cbc', > 'blowfish-cbc', 'cast128-cbc', 'arcfour128', 'arcfour256', 'arcfour', > 'aes192-cbc', 'aes256-cbc', 'rijndael-...@lysator.liu.se', 'aes128-ctr', > 'aes192-ctr', 'aes256-ctr'] client mac:['hmac-md5', 'hmac-sha1', > 'hmac-ripemd160', 'hmac-ripemd...@openssh.com', 'hmac-sha1-96', > 'hmac-md5-96'] server mac:['hmac-md5', 'hmac-sha1', 'hmac-ripemd160', > 'hmac-ripemd...@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] client > compress:['none', 'z...@openssh.com'] server compress:['none', > 'z...@openssh.com'] client lang:[''] server lang:[''] kex follows?False > paramiko.transport: DEBUG: Ciphers agreed: local=aes128-ctr, remote=aes128-ctr > paramiko.transport: DEBUG: using kex diffie-hellman-group1-sha1; server key > type ssh-rsa; cipher: local aes128-ctr, remote aes128-ctr; mac: local > hmac-sha1, remote hmac-sha1; compression: local none, remote none > paramiko.transport: DEBUG: Switch to new keys ... > paramiko.transport: DEBUG: Adding ssh-rsa host key for 10.223.122.80: > 0f8b3ff9dc4dce10340227dab3cac032 > paramiko.transport: DEBUG: Trying discovered key > 76be480fa6b8ad3b78082d6d19e4ee44 in /root/.ssh/id_rsa > paramiko.transport: DEBUG: userauth is OK > paramiko.transport: INFO: Authentication (publickey) failed. > paramiko.transport: DEBUG: userauth is OK > paramiko.transport: INFO: Authentication (password) successful! > sshClient: DEBUG: SSH connect: root@10.223.122.80 with passwd password > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: SSH into VM is > successfully > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Verifying if we can > ping to outside world from VM? > paramiko.transport: DEBUG: [chan 1] Max packet in: 34816 bytes > paramiko.transport: DEBUG: [chan 1] Max packet out: 32768 bytes > paramiko.transport: INFO: Secsh channel 1 opened. > paramiko.transport: DEBUG: [chan 1] Sesch channel 1 request ok > paramiko.transport: DEBUG: [chan 1] EOF received (1) > sshClient: DEBUG: {Cmd: ping -c 1 www.google.com via Host: 10.223.122.80} > {returns: ['PING www.google.com (74.125.239.148) 56(84) bytes of data.', '64 > bytes from nuq05s02-in-f20.1e100.net (74.125.239.148): icmp_seq=1 ttl=48 > time=6.24 ms', '', '--- www.google.com ping statistics ---', '1 packets > transmitted, 1 received, 0% packet loss, time 0ms', 'rtt min/avg/max/mdev = > 6.249/6.249/6.249/0.000 ms']} > paramiko.transport: DEBUG: [chan 1] EOF sent (1) > paramiko.transport: DEBUG: EOF in transport thread > testclient.testcase.TestVMLifeCycleStoppedVPCVR: DEBUG: Migrating VM-ID: > 20b35944-9892-48bf-bd6d-a2338a7eb6ad to Host: > 44652e87-084e-4076-a068-291943ad92ba > --------------------- >> end captured logging << --------------------- > Stacktrace > File "/usr/local/lib/python2.7/unittest/case.py", line 318, in run > testMethod() > File > "/Repo_30X/ipcl/cloudstack/test/integration/component/test_vpc_vm_life_cycle.py", > line 2536, in test_07_migrate_instance_in_network > self.fail("Failed to migrate instance, %s" % e) > File "/usr/local/lib/python2.7/unittest/case.py", line 393, in fail > raise self.failureException(msg) > Failed to migrate instance, Execute cmd: asyncquery failed, due to: > {errorcode : 530, errortext : u'Cannot migrate VM, VM is already presnt on > this host, please specify valid destination host to migrate the VM'} > Observed below error in MS log > ----------------------------------------------- > rO9O2ItNksB9HPXXXbDCB5nnnmnast2F-e51Ik24J0-SxLBnsulcBnHJ1GNTKt0rfdZGSmI4YtJuUa2GEJMQ&command=migrateVirtualMachine&signature=8Th9GAOMs0LfB5nUClzYfn9sNSA%3D&response=json > 2013-08-14 07:05:13,104 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) > ===START=== 10.223.240.194 -- GET > signature=J6dWD736KAA3FeQiSIfJD34Y68o%3D&apiKey=o1rO9O2ItNksB9HPXXXbDCB5nnnmnast2F-e51Ik24J0-SxLBnsulcBnH > J1GNTKt0rfdZGSmI4YtJuUa2GEJMQ&command=queryAsyncJobResult&response=json&jobid=64530659-1b4b-4f5a-bf9d-f9ddf2b33000 > 2013-08-14 07:05:13,126 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) > ===END=== 10.223.240.194 -- GET > signature=J6dWD736KAA3FeQiSIfJD34Y68o%3D&apiKey=o1rO9O2ItNksB9HPXXXbDCB5nnnmnast2F-e51Ik24J0-SxLBnsulcBnHJ1 > GNTKt0rfdZGSmI4YtJuUa2GEJMQ&command=queryAsyncJobResult&response=json&jobid=64530659-1b4b-4f5a-bf9d-f9ddf2b33000 > 2013-08-14 07:05:13,138 ERROR [cloud.async.AsyncJobManagerImpl] > (Job-Executor-139:job-2319 = [ 64530659-1b4b-4f5a-bf9d-f9ddf2b33000 ]) > Unexpected exception while executing > org.apache.cloudstack.api.command.admin.vm.Mi > grateVMCmd > com.cloud.exception.InvalidParameterValueException: Cannot migrate VM, VM is > already presnt on this host, please specify valid destination host to migrate > the VM > at > com.cloud.vm.UserVmManagerImpl.migrateVirtualMachine(UserVmManagerImpl.java:3939) > at > com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125) > at > org.apache.cloudstack.api.command.admin.vm.MigrateVMCmd.execute(MigrateVMCmd.java:147) > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158) > at > com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531) > at > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) > at java.util.concurrent.FutureTask.run(FutureTask.java:166) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > 2013-08-14 07:05:13,139 DEBUG [cloud.async.AsyncJobManagerImpl] > (Job-Executor-139:job-2319 = [ 64530659-1b4b-4f5a-bf9d-f9ddf2b33000 ]) > Complete async job-2319 = [ 64530659-1b4b-4f5a-bf9d-f9ddf2b33000 ], > jobStatus: 2, resultCode: 530, result: Error Code: 530 Error text: Cannot > migrate VM, VM is already presnt on this host, please specify valid > destination host to migrate the VM > 2013-08-14 07:05:13,534 DEBUG [cloud.network.NetworkManagerImpl] > (Network-Scavenger-1:null) We found network 494 to be free for the first > time. Adding it to the list: 1344217103 > 2013-08-14 07:05:13,537 DEBUG [cloud.network.NetworkModelImpl] > (Network-Scavenger-1:null) Network id=497 is not ready for GC as it has vms > that are Starting at the moment -- This message was sent by Atlassian JIRA (v6.3.4#6332)