[
https://issues.apache.org/jira/browse/CLOUDSTACK-8668?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14643096#comment-14643096
]
Wilder Rodrigues commented on CLOUDSTACK-8668:
----------------------------------------------
Few things I got from the logs when creating a VM:
2015-07-27 13:28:20,921 DEBUG [c.c.h.x.r.w.x.CitrixStartCommandWrapper]
(DirectAgent-28:ctx-b81b9e66) 1. The VM r-4-VM is in Starting state.
2015-07-27 13:28:20,950 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) Created VM d42abe6d-d225-c738-d905-a303883cb093
for r-4-VM
2015-07-27 13:28:20,958 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) PV args are -- quiet
console=hvc0%template=domP%name=r-4-VM%eth0ip=192.168.22.66%eth0mask=255.255.255.0%gateway=192.168.22.1%domain=cs1cloud%cidrsize=24%dhcprange=192.168.22.1%eth1i
p=169.254.2.146%eth1mask=255.255.0.0%type=dhcpsrvr%disable_rp_filter=true%dns1=8.8.8.8%dns2=8.8.4.4%baremetalnotificationsecuritykey=aon4dbRYnNESI4PY3PDh64PgJKAx6H11OfLVwZAWE-4uJWV-iJrg7fcgdTHDVVjOP81TS0UBxKMmY53r0y88bw%baremetalnotificationapikey=CAC7caUw4uF9wfLNrSObdj-h
VkVSo9MQzEMIjN4rIfSjOAu8TJxVnHaFEU8W5utTWuuDJqHiqtXgTJT7T-Cm4w%host=192.168.22.61%port=8080
2015-07-27 13:28:21,006 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) VBD 6c7a704f-6043-d09e-ecbb-27e8b6378597 created
for com.cloud.agent.api.to.DiskTO@ad357be
2015-07-27 13:28:21,024 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) Creating VIF for r-4-VM on nic
[Nic:Guest-192.168.22.66-vlan://untagged]
2015-07-27 13:28:21,048 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) Created a vif
8db43efe-bac2-8b6e-d20f-b2f25445e6c1 on 0
2015-07-27 13:28:21,048 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) Creating VIF for r-4-VM on nic
[Nic:Control-169.254.2.146-null]
2015-07-27 13:28:21,099 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) already have a vif on dom0 for link local network
2015-07-27 13:28:21,284 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-28:ctx-b81b9e66) Created a vif
dbe914ad-eed8-c79c-0162-4cb7118ab701 on 1
2015-07-27 13:31:55,197 DEBUG [c.c.c.ClusterManagerImpl]
(Cluster-Heartbeat-1:ctx-4fe9ccf7) Management server heartbeat takes too long
to finish. profiler: Done. Duration: 5ms, profilerHeartbeatUpdate: Done.
Duration: 4ms, profilerPeerScan: Done. Duration: 1ms
2015-07-27 13:31:55,580 ERROR [c.c.u.s.SshHelper] (DirectAgent-28:ctx-b81b9e66)
Timed out in waiting SSH execution result
2015-07-27 13:31:55,581 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-28:ctx-b81b9e66) Seq 1-7644297417507995698: Response Received:
2015-07-27 13:31:55,583 DEBUG [c.c.a.t.Request] (DirectAgent-28:ctx-b81b9e66)
Seq 1-7644297417507995698: Processing: { Ans: , MgmtId: 3232241213, via: 1,
Ver: v1, Flags: 10,
[{"com.cloud.agent.api.StartAnswer":{"vm":{"id":4,"name":"r-4-VM","bootloader":"PyGrub","type":"DomainRouter","cpus":1,"minSpeed":500,"maxSpeed":500,"minRam":268435456,"maxRam":268435456,"arch":"x86_64","os":"Debian
GNU/Linux 7(64-bit)","platformEmulator":"Debian Wheezy 7.0
(64-bit)","bootArgs":" template=domP name=r-4-VM eth0ip=192.168.22.66
eth0mask=255.255.255.0 gateway=192.168.22.1 domain=cs1cloud cidrsize=24
dhcprange=192.168.22.1 eth1ip=169.254.2.146 eth1mask=255.255.0.0 type=dhcpsrvr
disable_rp_filter=true dns1=8.8.8.8 dns2=8.8.4.4
baremetalnotificationsecuritykey=aon4dbRYnNESI4PY3PDh64PgJKAx6H11OfLVwZAWE-4uJWV-iJrg7fcgdTHDVVjOP81TS0UBxKMmY53r0y88bw
baremetalnotificationapikey=CAC7caUw4uF9wfLNrSObdj-hVkVSo9MQzEMIjN4rIfSjOAu8TJxVnHaFEU8W5utTWuuDJqHiqtXgTJT7T-Cm4w
host=192.168.22.61
port=8080","enableHA":true,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"oc7KQtWwxoUHoIoNiRPhCA","params":{},"uuid":"309d19d3-cd56-44e2-8119-61afbb914f69","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"b4ce8d0e-e65d-46f2-987b-f297256904f7","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"7016d9c7-3dcd-3128-add6-5910036ef0b4","id":1,"poolType":"NetworkFilesystem","host":"192.168.22.1","path":"/data/storage/primary/MCCT-XEN-1","port":2049,"url":"NetworkFilesystem://192.168.22.1/data/storage/primary/MCCT-XEN-1/?ROLE=Primary&STOREUUID=7016d9c7-3dcd-3128-add6-5910036ef0b4"}},"name":"ROOT-4","size":3145728000,"path":"ccc53598-e9c9-4a22-a711-df836e63152e","volumeId":4,"vmName":"r-4-VM","accountId":1,"format":"VHD","provisioningType":"THIN","id":4,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"ccc53598-e9c9-4a22-a711-df836e63152e","type":"ROOT","_details":{"managed":"false","storagePort":"2049","storageHost":"192.168.22.1","volumeSize":"3145728000"}}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":true,"pxeDisable":true,"nicUuid":"83e48526-4ca9-4dfd-9d76-2a9c343f8139","uuid":"b35aec92-a197-4f6a-8a22-693dc1d294e2","ip":"192.168.22.66","netmask":"255.255.255.0","gateway":"192.168.22.1","mac":"06:a4:ee:00:00:2f","dns1":"8.8.8.8","dns2":"8.8.4.4","broadcastType":"Native","type":"Guest","broadcastUri":"vlan://untagged","isSecurityGroupEnabled":true},{"deviceId":1,"networkRateMbps":-1,"defaultNic":false,"pxeDisable":true,"nicUuid":"34748ee1-8759-4e7b-9e2b-9bab767e9185","uuid":"abecb8b4-7628-4d65-a60e-7fe8ac3c6264","ip":"169.254.2.146","netmask":"255.255.0.0","gateway":"169.254.0.1","mac":"0e:00:a9:fe:02:92","broadcastType":"LinkLocal","type":"Control","isSecurityGroupEnabled":false}]},"_iqnToPath":{},"result":true,"wait":0}},{"com.cloud.agent.api.check.CheckSshAnswer":{"result":true,"wait":0}},{"com.cloud.agent.api.GetDomRVersionAnswer":{"templateVersion":"Cloudstack
Release 4.6.0 Sun Jul 19 23:19:11 UTC
2015","scriptsVersion":"46b2bed7b526507fd399611823998baf\n","result":true,"details":"Cloudstack
Release 4.6.0 Sun Jul 19 23:19:11 UTC
2015&46b2bed7b526507fd399611823998baf\n","wait":0}},{"com.cloud.agent.api.NetworkUsageAnswer":{"routerName":"r-4-VM","bytesSent":0,"bytesReceived":0,"result":true,"details":"","wait":0}},{"com.cloud.agent.api.Answer":{"result":true,"details":"Command
aggregation
started","wait":0}},{"com.cloud.agent.api.Answer":{"result":true,"wait":0}},{"com.cloud.agent.api.Answer":{"result":false,"details":"Timed
out in waiting SSH execution result","wait":0}}] }
2015-07-27 13:31:55,583 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-6:ctx-8b1fb46b job-19/job-20 ctx-1bfce368) Seq
1-7644297417507995698: Received: { Ans: , MgmtId: 3232241213, via: 1, Ver: v1,
Flags: 10, { StartAnswer, CheckSshAnswer, GetDomRVersionAnswer,
NetworkUsageAnswer, Answer, Answer, Answer } }
2015-07-27 13:31:55,592 WARN [o.a.c.alerts] (Work-Job-Executor-6:ctx-8b1fb46b
job-19/job-20 ctx-1bfce368) alertType:: 9 // dataCenterId:: 1 // podId:: 1 //
clusterId:: null // message:: Command: com.cloud.agent.api.Command failed while
starting virtual router
2015-07-27 13:31:55,596 ERROR [c.c.n.r.VirtualNetworkApplianceManagerImpl]
(Work-Job-Executor-6:ctx-8b1fb46b job-19/job-20 ctx-1bfce368) Timed out in
waiting SSH execution result
2015-07-27 13:31:55,597 WARN [c.c.n.r.VirtualNetworkApplianceManagerImpl]
(Work-Job-Executor-6:ctx-8b1fb46b job-19/job-20 ctx-1bfce368) Command:
com.cloud.agent.api.Command failed while starting virtual router
2015-07-27 13:31:55,597 INFO [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-6:ctx-8b1fb46b job-19/job-20 ctx-1bfce368) The guru did not
like the answers so stopping VM[DomainRouter|r-4-VM]
2015-07-27 13:31:55,600 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-6:ctx-8b1fb46b job-19/job-20 ctx-1bfce368) Seq
1-7644297417507995703: Sending { Cmd , MgmtId: 3232241213, via: 1(xen2), Ver:
v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"r-4-VM","wait":0}}]
}
2015-07-27 13:31:55,600 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-6:ctx-8b1fb46b job-19/job-20 ctx-1bfce368) Seq
1-7644297417507995703: Executing: { Cmd , MgmtId: 3232241213, via: 1(xen2),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"r-4-VM","wait":0}}]
}
2015-07-27 13:31:55,600 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-31:ctx-02554659) Seq 1-7644297417507995703: Executing request
2015-07-27 13:31:55,640 DEBUG [c.c.h.x.r.w.x.CitrixStopCommandWrapper]
(DirectAgent-31:ctx-02554659) 9. The VM r-4-VM is in Stopping state
2015-07-27 13:31:56,096 INFO [c.c.h.x.r.w.x.CitrixStopCommandWrapper]
(DirectAgent-31:ctx-02554659) Removed network rules for vm r-4-VM
I will continue investigating.
> VR does not start in basic zone since ip address are not being configured on
> it
> -------------------------------------------------------------------------------
>
> Key: CLOUDSTACK-8668
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-8668
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Network Controller
> Affects Versions: 4.6.0
> Environment: Latest build from ACS master
> Reporter: Sanjeev N
> Assignee: Wilder Rodrigues
> Priority: Blocker
>
> VR does not start in basic zone since ip address are not being configured on
> it
> Steps to reproduce:
> ================
> 1.Bring up CS in basic zone with xen server cluster
> 2.Try to deploy one guest vm using default cent os template
> Expected Result:
> ==============
> VR should come up as part of vm deployment and vm deployment should be
> successfull
> Actual Result:
> ============
> VR creation failed since the IP addresses not are getting assigned to VR's
> guest and link local interfaces.
> Observations:
> ===========
> 1.During vr boot time, cloud-early-config ran successfully and VR console
> output showed that ping to gateway was successful. However, after VR boot we
> don't see any ip addresses on the VRs guest and link local ip address.
> 2. If we run cloud-early-config manually from VR , ip addresses will be
> assigned and persistent.
> Impact:
> =====
> VM deployments will fail since VR remains in stopped state.
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
