Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id E07D610194 for ; Mon, 19 Aug 2013 10:13:49 +0000 (UTC) Received: (qmail 99857 invoked by uid 500); 19 Aug 2013 10:13:49 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 99717 invoked by uid 500); 19 Aug 2013 10:13:49 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 99701 invoked by uid 500); 19 Aug 2013 10:13:48 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 99696 invoked by uid 99); 19 Aug 2013 10:13:48 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 19 Aug 2013 10:13:48 +0000 Date: Mon, 19 Aug 2013 10:13:48 +0000 (UTC) From: "ASF subversion and git services (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-4340) [Automation] Test cases TestVMLifeCycleStoppedVPCVR.test_07_migrate_instance_in_network failed during migration MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CLOUDSTACK-4340?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13743693#comment-13743693 ] ASF subversion and git services commented on CLOUDSTACK-4340: ------------------------------------------------------------- Commit 0eb1690872f0c46b4523331534500c9a68f250f7 in branch refs/heads/master from [~tsp] [ https://git-wip-us.apache.org/repos/asf?p=cloudstack.git;h=0eb1690 ] CLOUDSTACK-4340: Add more debug logging VM (id) moves from host (id) to host (id) Signed-off-by: Prasanna Santhanam (cherry picked from commit da0bb630057550cb5707a39cddb58a86a24850dd) > [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: Bug > 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 > Fix For: 4.2.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-cbc@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-cbc@lysator.liu.se', 'aes128-ctr', 'aes192-ctr', 'aes256-ctr'] client mac:['hmac-md5', 'hmac-sha1', 'hmac-ripemd160', 'hmac-ripemd160@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] server mac:['hmac-md5', 'hmac-sha1', 'hmac-ripemd160', 'hmac-ripemd160@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] client compress:['none', 'zlib@openssh.com'] server compress:['none', 'zlib@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-cbc@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-cbc@lysator.liu.se', 'aes128-ctr', 'aes192-ctr', 'aes256-ctr'] client mac:['hmac-md5', 'hmac-sha1', 'hmac-ripemd160', 'hmac-ripemd160@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] server mac:['hmac-md5', 'hmac-sha1', 'hmac-ripemd160', 'hmac-ripemd160@openssh.com', 'hmac-sha1-96', 'hmac-md5-96'] client compress:['none', 'zlib@openssh.com'] server compress:['none', 'zlib@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 is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrators For more information on JIRA, see: http://www.atlassian.com/software/jira