Sailaja Mada created CLOUDSTACK-3499: ----------------------------------------
Summary: AttachVolume is mandating Xen PV drivers to be installed Key: CLOUDSTACK-3499 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3499 Project: CloudStack Issue Type: Bug Security Level: Public (Anyone can view this level - this is the default.) Components: Storage Controller Affects Versions: 4.2.0 Reporter: Sailaja Mada Priority: Critical Setup: XenServer 6.2 , ACS 4.2 Steps : 1. Create user account and deploy VM with template which does not have PV drivers installed 2. Create new DATA volume using small disk offering. 3. Tried to attach volume to the instance. Observation: AttachVolume is mandating Xen PV drivers to be installed Note : 1. Attach volume is working if instance is deployed with PV drivers installed template (Default Cent OS) 2. PV drivers were mandatory only for migration in the previous releases. Now it is mandating for attach volume also. 2013-07-12 14:07:44,657 DEBUG [datastore.driver.CloudStackPrimaryDataStoreDriverImpl] (Job-Executor-14:job-56) Creating volume: org.apache.cloudstack.storage.volume.VolumeObject@26c3dc39 2013-07-12 14:07:44,662 DEBUG [agent.transport.Request] (Job-Executor-14:job-56) Seq 1-1058341678: Sending { Cmd , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CreateObjectCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"78e945fb-0255-42a4-a0da-609d67b726f0","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ae321c7e-120b-38d9-b732-20b2ea8c58b4","id":4,"poolType":"IscsiLUN","host":"10.102.192.101","path":"/iqn.2004-04.com.qnap:ts-659proii:iscsi.lun2.cf5b54/0","port":3260}},"name":"vol1","size":5368709120,"volumeId":17,"accountId":3,"id":17}},"wait":0}}] } 2013-07-12 14:07:44,662 DEBUG [agent.transport.Request] (Job-Executor-14:job-56) Seq 1-1058341678: Executing: { Cmd , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CreateObjectCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"78e945fb-0255-42a4-a0da-609d67b726f0","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ae321c7e-120b-38d9-b732-20b2ea8c58b4","id":4,"poolType":"IscsiLUN","host":"10.102.192.101","path":"/iqn.2004-04.com.qnap:ts-659proii:iscsi.lun2.cf5b54/0","port":3260}},"name":"vol1","size":5368709120,"volumeId":17,"accountId":3,"id":17}},"wait":0}}] } 2013-07-12 14:07:44,662 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-393:null) Seq 1-1058341678: Executing request 2013-07-12 14:07:44,748 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-393:null) SR retrieved for ae321c7e-120b-38d9-b732-20b2ea8c58b4 2013-07-12 14:07:44,753 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-393:null) Checking ae321c7e-120b-38d9-b732-20b2ea8c58b4 or SR f7b947d6-0e8c-3bab-0b78-d8dbce0938fa on XS[5b53e784-364d-4f85-bec6-f83be13334e8-10.102.192.17] 2013-07-12 14:07:45,177 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM 2013-07-12 14:07:45,444 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy 2013-07-12 14:07:45,694 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-393:null) Seq 1-1058341678: Response Received: 2013-07-12 14:07:45,695 DEBUG [agent.transport.Request] (DirectAgent-393:null) Seq 1-1058341678: Processing: { Ans: , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CreateObjectAnswer":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"name":"vol1","size":5368709120,"path":"ddec79d5-6926-4753-ac4d-5e6b8f7906a3","accountId":0,"id":0}},"result":true,"wait":0}}] } 2013-07-12 14:07:45,695 DEBUG [agent.transport.Request] (Job-Executor-14:job-56) Seq 1-1058341678: Received: { Ans: , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 10, { CreateObjectAnswer } } 2013-07-12 14:07:45,729 DEBUG [agent.transport.Request] (Job-Executor-14:job-56) Seq 1-1058341679: Sending { Cmd , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.AttachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"78e945fb-0255-42a4-a0da-609d67b726f0","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ae321c7e-120b-38d9-b732-20b2ea8c58b4","id":4,"poolType":"IscsiLUN","host":"10.102.192.101","path":"/iqn.2004-04.com.qnap:ts-659proii:iscsi.lun2.cf5b54/0","port":3260}},"name":"vol1","size":5368709120,"path":"ddec79d5-6926-4753-ac4d-5e6b8f7906a3","volumeId":17,"accountId":3,"format":"VHD","id":17}},"diskSeq":1,"type":"DATADISK"},"vmName":"i-3-8-VM","_storageHost":"10.102.192.101","_storagePort":3260,"_managed":false,"wait":0}}] } 2013-07-12 14:07:45,730 DEBUG [agent.transport.Request] (Job-Executor-14:job-56) Seq 1-1058341679: Executing: { Cmd , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.AttachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"78e945fb-0255-42a4-a0da-609d67b726f0","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ae321c7e-120b-38d9-b732-20b2ea8c58b4","id":4,"poolType":"IscsiLUN","host":"10.102.192.101","path":"/iqn.2004-04.com.qnap:ts-659proii:iscsi.lun2.cf5b54/0","port":3260}},"name":"vol1","size":5368709120,"path":"ddec79d5-6926-4753-ac4d-5e6b8f7906a3","volumeId":17,"accountId":3,"format":"VHD","id":17}},"diskSeq":1,"type":"DATADISK"},"vmName":"i-3-8-VM","_storageHost":"10.102.192.101","_storagePort":3260,"_managed":false,"wait":0}}] } 2013-07-12 14:07:45,730 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-143:null) Seq 1-1058341679: Executing request 2013-07-12 14:07:45,819 WARN [xen.resource.XenServerStorageProcessor] (DirectAgent-143:null) : You attempted an operation on a VM which requires PV drivers to be installed but the drivers were not detected 2013-07-12 14:07:45,819 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-143:null) Seq 1-1058341679: Response Received: 2013-07-12 14:07:45,820 DEBUG [agent.transport.Request] (DirectAgent-143:null) Seq 1-1058341679: Processing: { Ans: , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.AttachAnswer":{"result":false,"details":"You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso.","wait":0}}] } 2013-07-12 14:07:45,820 DEBUG [agent.transport.Request] (Job-Executor-14:job-56) Seq 1-1058341679: Received: { Ans: , MgmtId: 280320865129348, via: 1, Ver: v1, Flags: 10, { AttachAnswer } } 2013-07-12 14:07:45,825 ERROR [cloud.async.AsyncJobManagerImpl] (Job-Executor-14:job-56) Unexpected exception while executing org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd com.cloud.utils.exception.CloudRuntimeException: Failed to attach volume: vol1 to VM: sailaja1i1; You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso. at com.cloud.storage.VolumeManagerImpl.sendAttachVolumeCommand(VolumeManagerImpl.java:1678) at com.cloud.storage.VolumeManagerImpl.attachVolumeToVM(VolumeManagerImpl.java:1885) at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125) at org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd.execute(AttachVolumeCmd.java:122) at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155) at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:513) 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-07-12 14:07:45,828 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-14:job-56) Complete async job-56, jobStatus: 2, resultCode: 530, result: Error Code: 530 Error text: Failed to attach volume: vol1 to VM: sailaja1i1; You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso. 2013-07-12 14:07:46,115 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 3 routers to update status. 2013-07-12 14:07:46,117 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status. 2013-07-12 14:07:46,162 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 3 routers to update status. 2013-07-12 14:07:46,163 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status. 2013-07-12 14:07:47,599 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START=== 10.144.6.19 -- GET command=queryAsyncJobResult&jobId=2f3c8289-e9e5-46fa-bb2b-b62590b7059d&response=json&sessionkey=e2%2Fcr9mQGrsQ8hLciCnPWCo4Y8s%3D&_=1373618399741 2013-07-12 14:07:47,608 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-22:null) Async job-56 completed 2013-07-12 14:07:47,612 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END=== 10.144.6.19 -- GET command=queryAsyncJobResult&jobId=2f3c8289-e9e5-46fa-bb2b-b62590b7059d&response=json&sessionkey=e2%2Fcr9mQGrsQ8hLciCnPWCo4Y8s%3D&_=1373618399741 -- 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