You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Rayees Namathponnan (JIRA)" <ji...@apache.org> on 2013/10/22 05:54:43 UTC

[jira] [Updated] (CLOUDSTACK-4915) [Automation][vmware] Router deployment failed with error "vm_data command on domain router 10.223.xxx.xxx failed"

     [ https://issues.apache.org/jira/browse/CLOUDSTACK-4915?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Rayees Namathponnan updated CLOUDSTACK-4915:
--------------------------------------------

    Summary: [Automation][vmware] Router deployment failed with error "vm_data command on domain router 10.223.xxx.xxx failed"  (was: [Automation][vmware] Router deployment failed with errro "vm_data command on domain router 10.223.xxx.xxx failed")

> [Automation][vmware] Router deployment failed with error "vm_data command on domain router 10.223.xxx.xxx failed"
> -----------------------------------------------------------------------------------------------------------------
>
>                 Key: CLOUDSTACK-4915
>                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4915
>             Project: CloudStack
>          Issue Type: Bug
>      Security Level: Public(Anyone can view this level - this is the default.) 
>          Components: Virtual Router, VMware
>    Affects Versions: 4.2.1
>         Environment: vmware 
> vcenter : 5.0.0
> build : 4.2 branch build
>            Reporter: Rayees Namathponnan
>            Priority: Blocker
>             Fix For: 4.2.1
>
>
> Steps to reproduce 
> Step 1: Deploy advanced zone on vmware
> Step 2 : Deploy a new VM
> Expected result 
> New VR and vm should be deployed successfully
> Actual Result 
> VR deployment failed, with below error 
> dVXhBKX1DBZN_tjeswqFitEBpuCw_raBdDlth5a6NN3F-Ogc-7xOMd8KRXkiy_iLX7-PrxbKXTA&command=queryAsyncJobResult&response=json&jobid=4bca94d1-c42a-4e74-ac93-cdb16785dcfa
> 2013-10-21 19:13:37,488 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.223.240.195 -- GET  signature=T3JZT8PCPsr0U0uWSzz3A212OsI%3D&apiKey=7FTLECSOHHudVXhB
> KX1DBZN_tjeswqFitEBpuCw_raBdDlth5a6NN3F-Ogc-7xOMd8KRXkiy_iLX7-PrxbKXTA&command=queryAsyncJobResult&response=json&jobid=471b9d49-3d61-458c-8155-0153f5e81c62
> 2013-10-21 19:13:37,500 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===END===  10.223.240.195 -- GET  signature=hu%2BTHqM9tMzwxlca940XFuy%2Fpe8%3D&apiKey=7FTLECSOHHudV
> XhBKX1DBZN_tjeswqFitEBpuCw_raBdDlth5a6NN3F-Ogc-7xOMd8KRXkiy_iLX7-PrxbKXTA&command=queryAsyncJobResult&response=json&jobid=4bca94d1-c42a-4e74-ac93-cdb16785dcfa
> 2013-10-21 19:13:37,600 INFO  [vmware.mo.HypervisorHostHelper] (DirectAgent-58:10.223.250.130) Waiting for network cloud.guest.3188.200.1-vSwitch0 to be ready
> 2013-10-21 19:13:38,564 ERROR [vmware.resource.VmwareResource] (DirectAgent-54:10.223.250.131) vm_data command on domain router 10.223.250.182 failed. messge: + PORT=3922
> + vmIp=
> + folder=
> + file=
> + dataFile=
> + getopts v:F:f:d: OPTION
> + case $OPTION in
> + vmIp=10.1.1.140
> + getopts v:F:f:d: OPTION
> TION in\n+ dataFile=/tmp/tmp7EAaaX\n+ getopts v:F:f:d: OPTION\n+ '[' 10.1.1.140 == '' ']'\n+ '[' metadata == '' ']'\n+ '[' public-keys == '' ']'\n+ '[' metadata '!=' userdata
>  ']'\n+ '[' metadata '!=' metadata ']'\n+ '[' /tmp/tmp7EAaaX '!=' '' ']'\n+ create_htaccess 10.1.1.140 metadata public-keys\n+ local vmIp=10.1.1.140\n+ local folder=metadata\n
> + local file=public-keys\n+ local result=0\n+ entry='RewriteRule ^public-keys$ ../metadata/%{REMOTE_ADDR}/public-keys [L,NC,QSA]'\n+ htaccessFolder=/var/www/html/latest\n+ hta
> ccessFile=/var/www/html/latest/.htaccess\n+ mkdir -p /var/www/html/latest\n+ touch /var/www/html/latest/.htaccess\n+ grep -Fq 'RewriteRule ^public-keys$ ../metadata/%{REMOTE_A
> DDR}/public-keys [L,NC,QSA]' /var/www/html/latest/.htaccess\n+ echo -e RewriteRule '^public-keys$' '../metadata/%{REMOTE_ADDR}/public-keys' '[L,NC,QSA]'\n+ result=0\n+ entry='
> Options -Indexes\\nOrder Deny,Allow\\nDeny from all\\nAllow from 10.1.1.140'\n+ testentry='Allow from 10.1.1.140'\n+ htaccessFolder=/var/www/html/metadata/10.1.1.140\n+ htacce
> ssFile=/var/www/html/metadata/10.1.1.140/.htaccess\n+ grep -Fq 'Allow from 10.1.1.140' /var/www/html/metadata/10.1.1.140/.htaccess\n+ htaccessFileNoIP=/var/www/html/latest/.ht
> access\n+ metadataentry1='RewriteRule ^meta-data/$ ../metadata/%{REMOTE_ADDR}/meta-data [L,NC,QSA]'\n+ metadataentry2='RewriteRule ^meta-data/(.*)$ ../metadata/%{REMOTE_ADDR}/
> $1 [L,NC,QSA]'\n+ grep -Fq 'RewriteRule ^meta-data/$ ../metadata/%{REMOTE_ADDR}/meta-data [L,NC,QSA]' /var/www/html/latest/.htaccess\n+ grep -Fq 'RewriteRule ^meta-data/(.*)$
> ../metadata/%{REMOTE_ADDR}/$1 [L,NC,QSA]' /var/www/html/latest/.htaccess\n+ return 0\n+ '[' 0 -gt 0 ']'\n+ copy_vm_data_file 10.1.1.140 metadata public-keys /tmp/tmp7EAaaX\n+
> local vmIp=10.1.1.140\n+ local folder=metadata\n+ local file=public-keys\n+ local dataFile=/tmp/tmp7EAaaX\n+ dest=/var/www/html/metadata/10.1.1.140/public-keys\n+ metamanifest
> =/var/www/html/metadata/10.1.1.140/meta-data\n+ chmod +r /tmp/tmp7EAaaX\n+ cp /tmp/tmp7EAaaX /var/www/html/metadata/10.1.1.140/public-keys\n+ chmod 644 /var/www/html/metadata/
> 10.1.1.140/public-keys\n+ touch /var/www/html/metadata/10.1.1.140/meta-data\n+ chmod 644 /var/www/html/metadata/10.1.1.140/meta-data\n+ '[' metadata == metadata ']'\n+ sed -i
> '/$file/d' /var/www/html/metadata/10.1.1.140/meta-data\n+ echo public-keys\n+ return 0\n+ unlock_exit 0 biglock 1\n+ releaseLockFile biglock 1\n+ __LOCKDIR=/tmp\n+ __LOCKFILE=
> '/tmp/*-4212-biglock.lock'\n+ __locked=1\n+ '[' 1 == 1 ']'\n+ rm /tmp/1382390964048430565-4212-biglock.lock\n+ exit 0\n","wait":0}}] }
> 2013-10-21 19:13:38,790 DEBUG [agent.transport.Request] (Job-Executor-55:job-65 = [ 474d7a20-6e35-4311-a0ae-c39b99258ab9 ]) Seq 1-87359741: Received:  { Ans: , MgmtId: 90928106758026, via: 1, Ver: v1, Flags: 110, { Answer, Answer } }
> 2013-10-21 19:13:38,790 INFO  [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-55:job-65 = [ 474d7a20-6e35-4311-a0ae-c39b99258ab9 ]) Unable to contact resource.
> com.cloud.exception.ResourceUnavailableException: Resource [DataCenter:1] is unreachable: Unable to apply userdata and password entry on router
>         at com.cloud.network.router.VirtualNetworkApplianceManagerImpl.applyRules(VirtualNetworkApplianceManagerImpl.java:3827)
>         at com.cloud.network.router.VirtualNetworkApplianceManagerImpl.applyUserData(VirtualNetworkApplianceManagerImpl.java:3017)
>         at com.cloud.network.element.VirtualRouterElement.addPasswordAndUserdata(VirtualRouterElement.java:930)
>         at com.cloud.network.NetworkManagerImpl.prepareElement(NetworkManagerImpl.java:2085)
>         at com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java:2200)
>         at com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:2136)
>         at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:886)
>         at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
>         at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:227)
>         at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
>         at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3421)
>         at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2981)
>         at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2967)
>         at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
>         at org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
>         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:722)
> 2013-10-21 19:13:38,793 DEBUG [agent.manager.AgentAttache] (DirectAgent-54:null) Seq 1-87359742: Sending now.  is current sequence.
> 2013-10-21 19:13:38,793 DEBUG [agent.transport.Request] (DirectAgent-54:null) Seq 1-87359742: Executing:  { Cmd , MgmtId: 90928106758026, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.routing.SavePasswordCommand":{"password":"fnirq_cnffjbeq","vmIpAddress":"10.1.1.2","vmName":"349d554a-329c-433c-9d36-2c2b999cbabc","executeInSequence":true,"accessDetails":{"zone.network.type":"Advanced","router.name":"r-22-TestVM","router.ip":"10.223.250.175","router.guest.ip":"10.1.1.1"},"wait":0}},{"com.cloud.agent.api.routing.VmDataCommand":{"vmIpAddress":"10.1.1.2","vmName":"349d554a-329c-433c-9d36-2c2b999cbabc","executeInSequence":true,"accessDetails":{"zone.network.type":"Advanced","router.name":"r-22-TestVM","router.ip":"10.223.250.175","router.guest.ip":"10.1.1.1"},"wait":0}}] }
> 2013-10-21 19:13:38,794 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-145:null) Seq 1-87359742: Executing request
>  



--
This message was sent by Atlassian JIRA
(v6.1#6144)