[
https://issues.apache.org/jira/browse/CLOUDSTACK-182?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13461078#comment-13461078
]
Abhinav Roy commented on CLOUDSTACK-182:
----------------------------------------
Here is what I found in agent logs after enabling DEBUG
2012-09-22 15:20:08,396 INFO [utils.nio.NioClient] (Agent-Selector:null)
Connecting to 10.102.125.201:8250
2012-09-22 15:20:08,590 INFO [utils.nio.NioClient] (Agent-Selector:null) SSL:
Handshake done
2012-09-22 15:20:08,825 DEBUG [kvm.resource.LibvirtCapXMLParser]
(Agent-Handler-1:null) Found /usr/libexec/qemu-kvm as a suiteable emulator
2012-09-22 15:20:08,825 DEBUG [kvm.resource.LibvirtComputingResource]
(Agent-Handler-1:null) Executing: /bin/bash -c qemu-img --help|grep convert
|grep snapshot
2012-09-22 15:20:08,832 DEBUG [kvm.resource.LibvirtComputingResource]
(Agent-Handler-1:null) Exit value is 1
2012-09-22 15:20:08,832 DEBUG [kvm.resource.LibvirtComputingResource]
(Agent-Handler-1:null)
2012-09-22 15:20:08,832 DEBUG [kvm.resource.LibvirtComputingResource]
(Agent-Handler-1:null) cpus=8, speed=2393, ram=16707985408, dom0ram=805306368
2012-09-22 15:20:08,838 DEBUG [cloud.resource.ServerResourceBase]
(Agent-Handler-1:null) Parameters for private nic: 10.102.125.222 -
78:2b:cb:1f:4f:83-255.255.255.0
2012-09-22 15:20:08,838 DEBUG [cloud.resource.ServerResourceBase]
(Agent-Handler-1:null) Parameters for storage nic: 10.102.125.222 -
78:2b:cb:1f:4f:83-255.255.255.0
2012-09-22 15:20:08,839 DEBUG [cloud.resource.ServerResourceBase]
(Agent-Handler-1:null) Parameters for pubic nic: 10.102.125.222 -
78:2b:cb:1f:4f:83-255.255.255.0
2012-09-22 15:20:08,839 DEBUG [kvm.resource.LibvirtComputingResource]
(Agent-Handler-1:null) Executing:
/usr/lib64/cloud/common/scripts/vm/hypervisor/versions.sh
2012-09-22 15:20:08,854 DEBUG [kvm.resource.LibvirtComputingResource]
(Agent-Handler-1:null) Execution is successful.
2012-09-22 15:20:08,868 DEBUG [kvm.storage.LibvirtStorageAdaptor]
(Agent-Handler-1:null) <pool type='dir'>
<name>b36b5981-3f6d-4aec-ba98-e3669e99dff0</name>
<uuid>b36b5981-3f6d-4aec-ba98-e3669e99dff0</uuid>
<target>
<path>/var/lib/libvirt/images/</path>
</target>
</pool>
2012-09-22 15:20:08,872 DEBUG [kvm.storage.LibvirtStorageAdaptor]
(Agent-Handler-1:null) org.libvirt.LibvirtException: operation failed: Storage
source conflict with pool: '3765dc20-6a45-4491-8328-409916f4dbdf'
2012-09-22 15:20:08,873 WARN [utils.nio.Task] (Agent-Handler-1:null) Caught
the following exception but pushing on
java.lang.NullPointerException
at
com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.createStoragePool(LibvirtStorageAdaptor.java:563)
at
com.cloud.hypervisor.kvm.storage.KVMStoragePoolManager.createStoragePool(KVMStoragePoolManager.java:57)
at
com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3245)
at com.cloud.agent.Agent.sendStartup(Agent.java:320)
at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:850)
at com.cloud.utils.nio.Task.run(Task.java:83)
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)
** What is the workaround for this?
> Not able to add KVM (rhel 6.3 ) host to the MS
> ----------------------------------------------
>
> Key: CLOUDSTACK-182
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-182
> Project: CloudStack
> Issue Type: Bug
> Components: KVM, Management Server
> Affects Versions: pre-4.0.0
> Environment: MS : Rhel 6.3
> HOST : KVM Rhel 6.3
> Build : Git Revision: 0699d65ef155aeff60aab294987815251cc94900 Git URL:
> https://git-wip-us.apache.org/repos/asf/incubator-cloudstack.git]
> http://jenkins.cloudstack.org/job/build-4.0-rhel63/56/artifact/CloudStack-oss-4.0.0-56.tar.bz2
> Reporter: Abhinav Roy
> Assignee: edison su
> Priority: Blocker
> Fix For: pre-4.0.0
>
> Attachments: agent.log, management-server.log
>
>
> Steps :
> =====================
> 1. Deploy a Advanced zone setup.
> 2. Install agent on the KVM host.
> 3. Add host to MS.
> Expected behaviour :
> =====================
> Host should be successfully added to the MS
> Observed behaviour :
> =====================
> 1. While adding the host to the MS for the first time I see these errors in
> Host's agent logs
> 2012-09-21 16:39:34,570 INFO [cloud.agent.Agent] (main:null) id is
> 2012-09-21 16:39:34,712 INFO
> [resource.virtualnetwork.VirtualRoutingResource] (main:null)
> VirtualRoutingResource _scriptDir to use: scripts/network/domr/kvm
> 2012-09-21 16:39:35,586 INFO [kvm.resource.LibvirtComputingResource]
> (main:null) No libvirt.vif.driver specififed. Defaults to BridgeVifDriver.
> 2012-09-21 16:39:35,608 INFO [cloud.agent.Agent] (main:null) Agent [id = new
> : type = LibvirtComputingResource : zone = 1 : pod = 1 : workers = 5 : host =
> 10.102.125.201 : port = 8250
> 2012-09-21 16:39:35,617 INFO [utils.nio.NioClient] (Agent-Selector:null)
> Connecting to 10.102.125.201:8250
> 2012-09-21 16:39:35,863 INFO [utils.nio.NioClient] (Agent-Selector:null)
> SSL: Handshake done
> 2012-09-21 16:39:36,153 WARN [utils.nio.Task] (Agent-Handler-1:null) Caught
> the following exception but pushing on
> java.lang.NullPointerException
> at
> com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.createStoragePool(LibvirtStorageAdaptor.java:563)
> at
> com.cloud.hypervisor.kvm.storage.KVMStoragePoolManager.createStoragePool(KVMStoragePoolManager.java:57)
> at
> com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.initialize(LibvirtComputingResource.java:3245)
> at com.cloud.agent.Agent.sendStartup(Agent.java:320)
> at com.cloud.agent.Agent$ServerHandler.doTask(Agent.java:850)
> at com.cloud.utils.nio.Task.run(Task.java:83)
> 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)
> 2. Management server log has this :
> 2012-09-21 15:37:59,353 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null)
> Invalid paramemter in URL found. param: hosttags=
> 2012-09-21 15:37:59,370 INFO [cloud.resource.ResourceManagerImpl]
> (catalina-exec-5:null) Trying to add a new host at http://10.102.125.222 in
> data center 1
> 2012-09-21 15:37:59,722 DEBUG [utils.ssh.SSHCmdHelper] (catalina-exec-5:null)
> Executing cmd: lsmod|grep kvm
> 2012-09-21 15:38:00,880 DEBUG [utils.ssh.SSHCmdHelper] (catalina-exec-5:null)
> lsmod|grep kvm output:kvm_intel 52570 0
> kvm 314739 1 kvm_intel
> 2012-09-21 15:38:01,757 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-2:null) VmStatsCollector is running...
> 2012-09-21 15:38:01,758 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-1:null) HostStatsCollector is running...
> 2012-09-21 15:38:01,774 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) StorageCollector is running...
> 2012-09-21 15:38:01,887 DEBUG [utils.ssh.SSHCmdHelper] (catalina-exec-5:null)
> Executing cmd: cloud-setup-agent -m 10.102.125.201 -z 1 -p 1 -c 1 -g
> c93ed88a-6b58-3c53-aea3-62cff49cafbc -a --pubNic=cloudbr0 --prvNic=cloudbr0
> --guestNic=cloudbr0
> 2012-09-21 15:38:17,107 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Skip capacity scan due to there is no Primary Storage
> UPintenance mode
> 2012-09-21 15:38:22,629 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null) Found 0 routers.
> 2012-09-21 15:38:36,437 DEBUG [utils.ssh.SSHCmdHelper] (catalina-exec-5:null)
> cloud-setup-agent -m 10.102.125.201 -z 1 -p 1 -c 1 -g
> c93ed88a-6b58-3c53-aea3-62cff49cafbc -a --pubNic=cloudbr0 --prvNic=cloudbr0
> --guestNic=cloudbr0 output:CloudStack Agent setup is done!
> Configure Cgroup ...
> 2012-09-21 15:38:47,107 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Skip capacity scan due to there is no Primary Storage
> UPintenance mode
> 2012-09-21 15:48:19,081 DEBUG [kvm.discoverer.KvmServerDiscoverer]
> (catalina-exec-21:null) Timeout, to wait for the host connecting to mgt svr,
> assuming it is failed
> 2012-09-21 15:48:19,084 WARN [cloud.resource.ResourceManagerImpl]
> (catalina-exec-21:null) Unable to find the server resources at
> http://10.102.125.222
> 2012-09-21 15:48:19,084 WARN [api.commands.AddHostCmd]
> (catalina-exec-21:null) Exception:
> com.cloud.exception.DiscoveryException: Unable to add the host
> at
> com.cloud.resource.ResourceManagerImpl.discoverHostsFull(ResourceManagerImpl.java:737)
> at
> com.cloud.resource.ResourceManagerImpl.discoverHosts(ResourceManagerImpl.java:544)
> at com.cloud.api.commands.AddHostCmd.execute(AddHostCmd.java:140)
> at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:138)
> at com.cloud.api.ApiServer.queueCommand(ApiServer.java:543)
> at com.cloud.api.ApiServer.handleRequest(ApiServer.java:422)
> at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:304)
> at com.cloud.api.ApiServlet.doGet(ApiServlet.java:63)
> at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
> at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
> at
> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
> at
> org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
> at
> org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
> at
> org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
> at
> org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
> at
> org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
> at
> org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:555)
> at
> org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
> at
> org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298)
> at
> org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:889)
> at
> org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:721)
> at
> org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:2268)
> 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)
> 2012-09-21 15:48:19,085 WARN [cloud.api.ApiDispatcher]
> (catalina-exec-21:null) class com.cloud.api.ServerApiException : Unable to
> add the host
--
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
