manasaveloori created CLOUDSTACK-4140:
-----------------------------------------
Summary: [Upgraded ENV][VMware]Failed to create volume from
snapshot.
Key: CLOUDSTACK-4140
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4140
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Storage Controller, Upgrade, VMware
Affects Versions: 4.2.0
Environment: upgraded from 3.0.7 to 4.2
Reporter: manasaveloori
Priority: Blocker
Fix For: 4.2.0
Steps:
1. Have a CS with 3.0.7 build.
2. Upgrade the build to 4.2.
3. Deploy a VM.
4. Take the snapshot of root volume.
5. Create volume from snapshot.
Observed the following exception:
2013-08-07 22:06:33,434 DEBUG [agent.transport.Request] (Job-Executor-6:job-254
= [ 4d2d144b-edfd-4612-be6e-660a5569fcfb ]) Seq 7-211091473: Received: { Ans:
, MgmtId: 7067804893289, via: 7, Ver: v1, Flags: 10, { CopyCmdAnswer } }
2013-08-07 22:06:33,455 DEBUG [db.Transaction.Transaction]
(Job-Executor-6:job-254 = [ 4d2d144b-edfd-4612-be6e-660a5569fcfb ]) Rolling
back the transaction: Time = 7 Name =
-AsyncJobManagerImpl$1.run:494-Executors$RunnableAdapter.call:471-FutureTask$Sync.innerRun:334-FutureTask.run:166-ThreadPoolExecutor.runWorker:1110-ThreadPoolExecutor$Worker.run:603-Thread.run:679;
called by
-Transaction.rollback:896-Transaction.removeUpTo:839-Transaction.close:663-TransactionContextBuilder.interceptException:63-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:133-GenericDaoBase.update:774-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-GenericDaoBase.update:1262-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-VolumeObject.processEvent:476-VolumeServiceImpl.createVolumeFromSnapshotCallback:600-NativeMethodAccessorImpl.invoke0:-2
2013-08-07 22:06:33,457 DEBUG [storage.volume.VolumeServiceImpl]
(Job-Executor-6:job-254 = [ 4d2d144b-edfd-4612-be6e-660a5569fcfb ]) create
volume from snapshot failed
com.cloud.utils.exception.CloudRuntimeException: DB Exception on:
com.mysql.jdbc.PreparedStatement@4810fe87: UPDATE volumes SET
volumes.path=_binary'e392523cf722440ca979b886bda8bf9e', volumes.pool_id=203,
volumes.size=null WHERE volumes.id = 77
at com.cloud.utils.db.GenericDaoBase.update(GenericDaoBase.java:821)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at com.cloud.utils.db.GenericDaoBase.update(GenericDaoBase.java:774)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at com.cloud.utils.db.GenericDaoBase.update(GenericDaoBase.java:1262)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
org.apache.cloudstack.storage.volume.VolumeObject.processEvent(VolumeObject.java:476)
at
org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromSnapshotCallback(VolumeServiceImpl.java:600)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at
org.apache.cloudstack.framework.async.AsyncCallbackDispatcher.dispatch(AsyncCallbackDispatcher.java:142)
at
org.apache.cloudstack.framework.async.InplaceAsyncCallbackDriver.performCompletionCallback(InplaceAsyncCallbackDriver.java:26)
at
org.apache.cloudstack.framework.async.AsyncCallbackDispatcher.complete(AsyncCallbackDispatcher.java:120)
at
org.apache.cloudstack.storage.motion.AncientDataMotionStrategy.copyAsync(AncientDataMotionStrategy.java:398)
at
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:55)
at
org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromSnapshot(VolumeServiceImpl.java:572)
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:553)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:603)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:1047)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:183)
at
org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd.execute(CreateVolumeCmd.java:189)
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:679)
Caused by:
com.mysql.jdbc.exceptions.jdbc4.MySQLIntegrityConstraintViolationException:
Column 'size' cannot be null
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at
sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
at
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:532)
at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)
at com.mysql.jdbc.Util.getInstance(Util.java:386)
at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1040)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4074)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4006)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2468)
at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2629)
at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2719)
at
com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155)
at
com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2450)
at
com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2371)
at
com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2355)
at
org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105)
at
org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:105)
at com.cloud.utils.db.GenericDaoBase.update(GenericDaoBase.java:813)
... 53 more
2013-08-07 22:06:33,460 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-6:job-254 = [ 4d2d144b-edfd-4612-be6e-660a5569fcfb ]) Failed to
create volume from snapshot:com.cloud.utils.exception.CloudRuntimeException: DB
Exception on: com.mysql.jdbc.PreparedStatement@4810fe87: UPDATE volumes SET
volumes.path=_binary'e392523cf722440ca979b886bda8bf9e', volumes.pool_id=203,
volumes.size=null WHERE volumes.id = 77
2013-08-07 22:06:33,460 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-6:job-254 = [ 4d2d144b-edfd-4612-be6e-660a5569fcfb ]) Failed to
create volume: 77
com.cloud.utils.exception.CloudRuntimeException: Failed to create volume from
snapshot:com.cloud.utils.exception.CloudRuntimeException: DB Exception on:
com.mysql.jdbc.PreparedStatement@4810fe87: UPDATE volumes SET
volumes.path=_binary'e392523cf722440ca979b886bda8bf9e', volumes.pool_id=203,
volumes.size=null WHERE volumes.id = 77
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:558)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:603)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:1047)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:183)
at
org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd.execute(CreateVolumeCmd.java:189)
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:679)
2013-08-07 22:06:33,486 DEBUG [agent.transport.Request] (StatsCollector-1:null)
Seq 12-239992849: Received: { Ans: , MgmtId: 7067804893289, via: 12, Ver: v1,
Flags: 10, { GetStorageStatsAnswer } }
2013-08-07 22:06:33,523 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-6:job-254 = [ 4d2d144b-edfd-4612-be6e-660a5569fcfb ]) Complete
async job-254 = [ 4d2d144b-edfd-4612-be6e-660a5569fcfb ], jobStatus: 2,
resultCode: 530, result: Error Code: 530 Error text: Failed to create a volume
Attaching the MS logs
--
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
