Sailaja Mada created CLOUDSTACK-3873:
----------------------------------------
Summary: No error notification is generated when Primary storage
(Cluster level) is added with wrong path
Key: CLOUDSTACK-3873
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3873
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Management Server
Affects Versions: 4.2.0
Reporter: Sailaja Mada
Priority: Minor
Steps:
1. Configure Adv zone with VMWARE host
2. Tried to add cluster level primary storage with wrong path
3. It is not added but there is no notification about the failure.
Only from MS logs Admin can figure out that it failed to add :
2013-07-27 12:15:04,954 DEBUG [agent.transport.Request] (StatsCollector-3:null)
Seq 1-1364136443: Received: { Ans: , MgmtId: 55638679939377, via: 1, Ver: v1,
Flags: 10, { GetVmStatsAnswer } }
2013-07-27 12:15:06,193 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-13:null) SeqA 2-10614: Processing Seq 2-10614: { Cmd ,
MgmtId: -1, via: 2, Ver: v1, Flags: 11,
[{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
\"connections\": []\n}","wait":0}}] }
2013-07-27 12:15:06,294 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-13:null) SeqA 2-10614: Sending Seq 2-10614: { Ans: ,
MgmtId: 55638679939377, via: 2, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2013-07-27 12:15:06,831 INFO [vmware.mo.HostMO]
(DirectAgent-341:10.102.192.19) Creation of NFS datastore on vCenter failed.
Details: vCenter API trace - mountDatastore(). target MOR: host-10, vmfs:
false, poolHost: 10.102.192.100, poolHostPort: 2049, poolPath:
/cpg_vol/sailaja/wrongps1, poolUuid: acae88b9f9213bb1bba37360ba60038b.
Exception mesg: An error occurred during host configuration.
2013-07-27 12:15:06,855 ERROR [vmware.resource.VmwareResource]
(DirectAgent-341:10.102.192.19) ModifyStoragePoolCommand failed due to
Exception: java.lang.Exception
Message: Creation of NFS datastore on vCenter failed.
java.lang.Exception: Creation of NFS datastore on vCenter failed.
at com.cloud.hypervisor.vmware.mo.HostMO.mountDatastore(HostMO.java:772)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.execute(VmwareResource.java:4104)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:472)
at
com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186)
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.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
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-07-27 12:15:06,857 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-341:null) Seq 1-1364136442: Response Received:
2013-07-27 12:15:06,857 DEBUG [agent.transport.Request] (DirectAgent-341:null)
Seq 1-1364136442: Processing: { Ans: , MgmtId: 55638679939377, via: 1, Ver:
v1, Flags: 10,
[{"com.cloud.agent.api.Answer":{"result":false,"details":"ModifyStoragePoolCommand
failed due to Exception: java.lang.Exception\nMessage: Creation of NFS
datastore on vCenter failed.\n","wait":0}}] }
2013-07-27 12:15:06,858 DEBUG [agent.transport.Request] (catalina-exec-22:null)
Seq 1-1364136442: Received: { Ans: , MgmtId: 55638679939377, via: 1, Ver: v1,
Flags: 10, { Answer } }
2013-07-27 12:15:06,858 DEBUG [agent.manager.AgentManagerImpl]
(catalina-exec-22:null) Details from executing class
com.cloud.agent.api.ModifyStoragePoolCommand: ModifyStoragePoolCommand failed
due to Exception: java.lang.Exception
Message: Creation of NFS datastore on vCenter failed.
2013-07-27 12:15:06,858 WARN [apache.cloudstack.alerts]
(catalina-exec-22:null) alertType:: 7 // dataCenterId:: 1 // podId:: 1 //
clusterId:: null // message:: Unable to attach storage pool2 to the host1
2013-07-27 12:15:06,943 WARN
[datastore.lifecycle.CloudStackPrimaryDataStoreLifeCycleImpl]
(catalina-exec-22:null) Unable to establish a connection between
Host[-1-Routing] and
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@473b266
com.cloud.utils.exception.CloudRuntimeException: Unable establish connection
from storage head to storage pool 2 due to ModifyStoragePoolCommand failed due
to Exception: java.lang.Exception
Message: Creation of NFS datastore on vCenter failed.
2
at
org.apache.cloudstack.storage.datastore.provider.DefaultHostListener.hostConnect(DefaultHostListener.java:66)
at
com.cloud.storage.StorageManagerImpl.connectHostToSharedPool(StorageManagerImpl.java:861)
at
org.apache.cloudstack.storage.datastore.lifecycle.CloudStackPrimaryDataStoreLifeCycleImpl.attachCluster(CloudStackPrimaryDataStoreLifeCycleImpl.java:390)
at
com.cloud.storage.StorageManagerImpl.createPool(StorageManagerImpl.java:725)
at
com.cloud.storage.StorageManagerImpl.createPool(StorageManagerImpl.java:176)
at
org.apache.cloudstack.api.command.admin.storage.CreateStoragePoolCmd.execute(CreateStoragePoolCmd.java:168)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at com.cloud.api.ApiServer.queueCommand(ApiServer.java:514)
at com.cloud.api.ApiServer.handleRequest(ApiServer.java:372)
at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:305)
at com.cloud.api.ApiServlet.doGet(ApiServlet.java:66)
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)
2013-07-27 12:15:06,952 WARN
[datastore.lifecycle.CloudStackPrimaryDataStoreLifeCycleImpl]
(catalina-exec-22:null) No host can access storage pool
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@473b266 on cluster
1
2013-07-27 12:15:06,977 DEBUG [cloud.storage.StorageManagerImpl]
(catalina-exec-22:null) Failed to add data store
com.cloud.utils.exception.CloudRuntimeException: Failed to access storage pool
at
org.apache.cloudstack.storage.datastore.lifecycle.CloudStackPrimaryDataStoreLifeCycleImpl.attachCluster(CloudStackPrimaryDataStoreLifeCycleImpl.java:401)
at
com.cloud.storage.StorageManagerImpl.createPool(StorageManagerImpl.java:725)
at
com.cloud.storage.StorageManagerImpl.createPool(StorageManagerImpl.java:176)
at
org.apache.cloudstack.api.command.admin.storage.CreateStoragePoolCmd.execute(CreateStoragePoolCmd.java:168)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at com.cloud.api.ApiServer.queueCommand(ApiServer.java:514)
at com.cloud.api.ApiServer.handleRequest(ApiServer.java:372)
at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:305)
at com.cloud.api.ApiServlet.doGet(ApiServlet.java:66)
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)
2013-07-27 12:15:06,995 INFO [cloud.api.ApiServer] (catalina-exec-22:null) null
2013-07-27 12:15:07,005 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null)
===END=== 10.104.255.13 -- GET
command=createStoragePool&scope=cluster&zoneid=f7c91fbd-7f40-4dec-ac6c-63c22d8b0374&podid=1c8f99d0-68d2-4cab-a5eb-4bff83828c49&clusterid=8f4828bb-2861-4505-9459-b6cb44664038&name=10.102.192.100&url=nfs%3A%2F%2F10.102.192.100%2Fcpg_vol%2Fsailaja%2Fwrongps1&tags=ps1&response=json&sessionkey=FpGQzDVW1TrcX9otTC%2BgTJJzDf4%3D&_=1374907697608
2013-07-27 12:15:16,153 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-12:null) SeqA 2-10615: Processing Seq 2-10615: { Cmd ,
MgmtId: -1, via: 2, Ver: v1, Flags: 11,
[{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
\"connections\": []\n}","wait":0}}] }
2013-07-27 12:15:16,160 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-12:null) SeqA 2-10615: Sending Seq 2-10615: { Ans: ,
MgmtId: 55638679939377, via: 2, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2013-07-27 12:15:17,643 DEBUG [storage.secondary.SecondaryStorageManagerImpl]
(secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2013-07-27 12:15:18,387 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
(consoleproxy-1:null) Zone 1 is ready to launch console proxy
2013-07-27 12:15:20,362 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:null) Found 4 routers to update status.
2013-07-27 12:15:20,366 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
2013-07-27 12:15:20,565 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:null) Found 4 routers to update status.
2013-07-27 12:15:20,569 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
2013-07-27 12:15:26,006 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Resetting hosts suitable for reconnect
2013-07-27 12:15:26,008 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Completed resetting hosts suitable for reconnect
--
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
