Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id C543CDA77 for ; Sat, 25 May 2013 02:02:20 +0000 (UTC) Received: (qmail 40299 invoked by uid 500); 25 May 2013 02:02:20 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 40233 invoked by uid 500); 25 May 2013 02:02:20 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 40226 invoked by uid 500); 25 May 2013 02:02:20 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 40221 invoked by uid 99); 25 May 2013 02:02:20 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 25 May 2013 02:02:20 +0000 Date: Sat, 25 May 2013 02:02:20 +0000 (UTC) From: "edison su (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Resolved] (CLOUDSTACK-2637) Object_Store_Refactor - DB Exception encountered when trying to upload volume. MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CLOUDSTACK-2637?page=3Dcom.atl= assian.jira.plugin.system.issuetabpanels:all-tabpanel ] edison su resolved CLOUDSTACK-2637. ----------------------------------- Resolution: Fixed =20 > Object_Store_Refactor - DB Exception encountered when trying to upload vo= lume. > -------------------------------------------------------------------------= ----- > > Key: CLOUDSTACK-2637 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-263= 7 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: Management Server > Affects Versions: 4.2.0 > Environment: build from object_store > Reporter: Sangeetha Hariharan > Assignee: edison su > Priority: Critical > Fix For: 4.2.0 > > > Object_Store_Refactor - DB Exception encountered when trying to upload vo= lume. > Steps to reproduce the problem: > Deploy a Vm. > Attach a data disk to it. > Write some changes to the data disk. > Take a snapshot of the datadisk. > Download this volume. > Now Upload this volume using uploadVolume command. > This operation fails with following error message: > DB Exception on: com.mysql.jdbc.JDBC4PreparedStatement@47c83c76: INSERT I= NTO volume_store_ref (volume_store_ref.store_id, volume_store_ref.volume_id= , volume_store_ref.zone_id, volume_store_ref.created, volume_store_ref.last= _updated, volume_store_ref.download_pct, volume_store_ref.size, volume_stor= e_ref.physical_size, volume_store_ref.download_state, volume_store_ref.chec= ksum, volume_store_ref.local_path, volume_store_ref.error_str, volume_store= _ref.job_id, volume_store_ref.install_path, volume_store_ref.url, volume_st= ore_ref.format, volume_store_ref.destroyed, volume_store_ref.update_count, = volume_store_ref.updated, volume_store_ref.state) VALUES (1, 10, 0, '2013-0= 5-22 23:26:12', null, 0, 0, 0, null, null, null, null, null, _binary'volume= s/2/10', null, null, 0, 0, null, 'Allocated') > 013-05-22 16:26:12,464 INFO [cloud.api.ApiServer] (catalina-exec-6:null)= (userId=3D2 accountId=3D2 sessionId=3DD91C4E4C17F0583A30B61BA0525D7FF8) 10= .216.51.144 -- GET command=3DuploadVolume&response=3Djson&sessionkey=3DYVb5= KPqcM3%2B%2BRpKwK3nTB2j6PTg%3D&name=3Dvolfromtest&zoneId=3D9c88115b-ff6e-47= af-9033-e592da1f395c&format=3DVHD&url=3Dhttp%3A%2F%2F10.223.110.232%2Ftempl= ates%2Ftest%2F64163ecc-060a-4a80-a396-54e57a2bae27.vhd&_=3D1369268599951 20= 0 { "uploadvolumeresponse" : {"jobid":"0227985e-52ea-4605-87b3-c06e2df8bef7= "} } > 2013-05-22 16:26:15,524 INFO [cloud.api.ApiServer] (catalina-exec-5:null= ) (userId=3D2 accountId=3D2 sessionId=3DD91C4E4C17F0583A30B61BA0525D7FF8) 1= 0.216.51.144 -- GET command=3DqueryAsyncJobResult&jobId=3D0227985e-52ea-460= 5-87b3-c06e2df8bef7&response=3Djson&sessionkey=3DYVb5KPqcM3%2B%2BRpKwK3nTB2= j6PTg%3D&_=3D1369268603245 200 { "queryasyncjobresultresponse" : {"accounti= d":"89db33ec-c193-11e2-8a23-6a01c7e420f0","userid":"89dc6e24-c193-11e2-8a23= -6a01c7e420f0","cmd":"org.apache.cloudstack.api.command.user.volume.UploadV= olumeCmd","jobstatus":2,"jobprocstatus":0,"jobresultcode":530,"jobresulttyp= e":"object","jobresult":{"errorcode":530,"errortext":"DB Exception on: com.= mysql.jdbc.JDBC4PreparedStatement@47c83c76: INSERT INTO volume_store_ref (v= olume_store_ref.store_id, volume_store_ref.volume_id, volume_store_ref.zone= _id, volume_store_ref.created, volume_store_ref.last_updated, volume_store_= ref.download_pct, volume_store_ref.size, volume_store_ref.physical_size, vo= lume_store_ref.download_state, volume_store_ref.checksum, volume_store_ref.= local_path, volume_store_ref.error_str, volume_store_ref.job_id, volume_sto= re_ref.install_path, volume_store_ref.url, volume_store_ref.format, volume_= store_ref.destroyed, volume_store_ref.update_count, volume_store_ref.update= d, volume_store_ref.state) VALUES (1, 10, 0, '2013-05-22 23:26:12', null, 0= , 0, 0, null, null, null, null, null, _binary'volumes/2/10', null, null, 0,= 0, null, 'Allocated')"},"created":"2013-05-22T16:26:12-0700","jobid":"0227= 985e-52ea-4605-87b3-c06e2df8bef7"} } > Management server logs : > 2013-05-22 16:26:12,362 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:nul= l) =3D=3D=3DSTART=3D=3D=3D 10.216.51.144 -- GET command=3DuploadVolume&re= sponse=3Djson&sessionkey=3DYVb5KPqcM3%2B%2BRpKwK3nTB2j6PTg%3D&name=3Dvolfro= mtest&zoneId=3D9c88115b-ff6e-47af-9033-e592da1f395c&format=3DVHD&url=3Dhttp= %3A%2F%2F10.223.110.232%2Ftemplates%2Ftest%2F64163ecc-060a-4a80-a396-54e57a= 2bae27.vhd&_=3D1369268599951 > 2013-05-22 16:26:12,460 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina= -exec-6:null) submit async job-50, details: AsyncJobVO {id:50, userId: 2, a= ccountId: 2, sessionKey: null, instanceType: None, instanceId: null, cmd: o= rg.apache.cloudstack.api.command.user.volume.UploadVolumeCmd, cmdOriginator= : null, cmdInfo: {"response":"json","sessionkey":"YVb5KPqcM3++RpKwK3nTB2j6P= Tg\u003d","ctxUserId":"2","name":"volfromtest","httpmethod":"GET","_":"1369= 268599951","ctxAccountId":"2","format":"VHD","ctxStartEventId":"149","url":= "http://10.223.110.232/templates/test/64163ecc-060a-4a80-a396-54e57a2bae27.= vhd","zoneId":"9c88115b-ff6e-47af-9033-e592da1f395c"}, cmdVersion: 0, callb= ackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode:= 0, result: null, initMsid: 206915885079359, completeMsid: null, lastUpdate= d: null, lastPolled: null, created: null} > 2013-05-22 16:26:12,462 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Exec= utor-27:job-50) Executing org.apache.cloudstack.api.command.user.volume.Upl= oadVolumeCmd for job-50 > 2013-05-22 16:26:12,464 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:nul= l) =3D=3D=3DEND=3D=3D=3D 10.216.51.144 -- GET command=3DuploadVolume&resp= onse=3Djson&sessionkey=3DYVb5KPqcM3%2B%2BRpKwK3nTB2j6PTg%3D&name=3Dvolfromt= est&zoneId=3D9c88115b-ff6e-47af-9033-e592da1f395c&format=3DVHD&url=3Dhttp%3= A%2F%2F10.223.110.232%2Ftemplates%2Ftest%2F64163ecc-060a-4a80-a396-54e57a2b= ae27.vhd&_=3D1369268599951 > 2013-05-22 16:26:12,508 DEBUG [db.Transaction.Transaction] (Job-Executor-= 27:job-50) Rolling back the transaction: Time =3D 1 Name =3D -AsyncJobMana= gerImpl$1.run:401-Executors$RunnableAdapter.call:471-FutureTask$Sync.innerR= un:334-FutureTask.run:166-ThreadPoolExecutor.runWorker:1110-ThreadPoolExecu= tor$Worker.run:603-Thread.run:679; called by -Transaction.rollback:890-Tran= saction.removeUpTo:833-Transaction.close:657-TransactionContextBuilder.inte= rceptException:63-ComponentInstantiationPostProcessor$InterceptorDispatcher= .intercept:133-ObjectInDataStoreManagerImpl.create:160-ImageStoreImpl.creat= e:142-VolumeServiceImpl.registerVolume:822-VolumeManagerImpl.uploadVolume:3= 97-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-= VolumeManagerImpl.uploadVolume:183-UploadVolumeCmd.execute:121 > 2013-05-22 16:26:12,519 ERROR [cloud.async.AsyncJobManagerImpl] (Job-Exec= utor-27:job-50) Unexpected exception while executing org.apache.cloudstack.= api.command.user.volume.UploadVolumeCmd > com.cloud.utils.exception.CloudRuntimeException: DB Exception on: com.mys= ql.jdbc.JDBC4PreparedStatement@47c83c76: INSERT INTO volume_store_ref (volu= me_store_ref.store_id, volume_store_ref.volume_id, volume_store_ref.zone_id= , volume_store_ref.created, volume_store_ref.last_updated, volume_store_ref= .download_pct, volume_store_ref.size, volume_store_ref.physical_size, volum= e_store_ref.download_state, volume_store_ref.checksum, volume_store_ref.loc= al_path, volume_store_ref.error_str, volume_store_ref.job_id, volume_store_= ref.install_path, volume_store_ref.url, volume_store_ref.format, volume_sto= re_ref.destroyed, volume_store_ref.update_count, volume_store_ref.updated, = volume_store_ref.state) VALUES (1, 10, 0, '2013-05-22 23:26:12', null, 0, 0= , 0, null, null, null, null, null, _binary'volumes/2/10', null, null, 0, 0,= null, 'Allocated') > at com.cloud.utils.db.GenericDaoBase.persist(GenericDaoBase.java:= 1342) > at com.cloud.utils.component.ComponentInstantiationPostProcessor$= InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:12= 5) > at org.apache.cloudstack.storage.datastore.ObjectInDataStoreManag= erImpl.create(ObjectInDataStoreManagerImpl.java:160) > at org.apache.cloudstack.storage.image.store.ImageStoreImpl.creat= e(ImageStoreImpl.java:142) > at org.apache.cloudstack.storage.volume.VolumeServiceImpl.registe= rVolume(VolumeServiceImpl.java:822) > at com.cloud.storage.VolumeManagerImpl.uploadVolume(VolumeManager= Impl.java:397) > at com.cloud.utils.component.ComponentInstantiationPostProcessor$= InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:12= 5) > at com.cloud.storage.VolumeManagerImpl.uploadVolume(VolumeManager= Impl.java:183) > at org.apache.cloudstack.api.command.user.volume.UploadVolumeCmd.= execute(UploadVolumeCmd.java:121) > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155) > at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.= java:437) > 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(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLIntegrityConstraintViolat= ionException: Column 'format' cannot be null > at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native = Method) > at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeCo= nstructorAccessorImpl.java:57) > at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Dele= gatingConstructorAccessorImpl.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(PreparedState= ment.java:2155) > at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStateme= nt.java:2450) > at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStateme= nt.java:2371) > at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStateme= nt.java:2355) > at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpd= ate(DelegatingPreparedStatement.java:105) > at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpd= ate(DelegatingPreparedStatement.java:105) > at com.cloud.utils.db.GenericDaoBase.persist(GenericDaoBase.java:= 1305) > ... 25 more > 2013-05-22 16:26:12,520 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Exec= utor-27:job-50) Complete async job-50, jobStatus: 2, resultCode: 530, resul= t: Error Code: 530 Error text: DB Exception on: com.mysql.jdbc.JDBC4Prepare= dStatement@47c83c76: INSERT INTO volume_store_ref (volume_store_ref.store_i= d, volume_store_ref.volume_id, volume_store_ref.zone_id, volume_store_ref.c= reated, volume_store_ref.last_updated, volume_store_ref.download_pct, volum= e_store_ref.size, volume_store_ref.physical_size, volume_store_ref.download= _state, volume_store_ref.checksum, volume_store_ref.local_path, volume_stor= e_ref.error_str, volume_store_ref.job_id, volume_store_ref.install_path, vo= lume_store_ref.url, volume_store_ref.format, volume_store_ref.destroyed, vo= lume_store_ref.update_count, volume_store_ref.updated, volume_store_ref.sta= te) VALUES (1, 10, 0, '2013-05-22 23:26:12', null, 0, 0, 0, null, null, nul= l, null, null, _binary'volumes/2/10', null, null, 0, 0, null, 'Allocated') > 2013-05-22 16:26:15,506 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:nul= l) =3D=3D=3DSTART=3D=3D=3D 10.216.51.144 -- GET command=3DqueryAsyncJobRe= sult&jobId=3D0227985e-52ea-4605-87b3-c06e2df8bef7&response=3Djson&sessionke= y=3DYVb5KPqcM3%2B%2BRpKwK3nTB2j6PTg%3D&_=3D1369268603245 > 2013-05-22 16:26:15,520 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina= -exec-5:null) Async job-50 completed -- This message is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrato= rs For more information on JIRA, see: http://www.atlassian.com/software/jira