SQL error with Backup for a server

[rajmartha]

We are seeing the below error for only one server… have you guys seen this error before?

ERROR o.h.e.j.b.i.BatchingBatch - HHH000315: Exception executing batch [java.sql.BatchUpdateException: Data truncation: Data too long for column ‘result_archive’ at row 1], SQL: update backup_result set config=?, result_base=?, executor_ip_address=?, size_in_mb=?, duration_millis=?, result_archive=?, last_updated=?, end_date=?, zone_id=?, result_bucket=?, status=?, result_path=? where id=?

Data truncation: Data too long for column ‘result_archive’ at row 1

ERROR o.h.e.j.s.SqlExceptionHelper - Data truncation: Data too long for column ‘result_archive’ at row 1

RROR c.m.b.BackupService - Error in handling message: could not execute batch; SQL [update backup_result set config=?, result_base=?, executor_ip_address=?, size_in_mb=?, duration_millis=?, result_archive=?, last_updated=?, end_date=?, zone_id=?, result_bucket=?, status=?, result_path=? where id=?]; nested exception is org.hibernate.exception.DataException: could not execute batch
2023-05-04_15:51:40.66924 'org.springframework.dao.DataIntegrityViolationException: could not execute batch; SQL [update backup_result set config=?, result_base=?, executor_ip_address=?, size_in_mb=?, duration_millis=?, result_archive=?, last_updated=?, end_date=?, zone_id=?, result_bucket=?, status=?, result_path=? where id=?]; nested exception is org.hibernate.exception.DataException: could not execute batch
2023-05-04_15:51:40.66925 at org.springframework.orm.hibernate5.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:216)
2023-05-04_15:51:40.66925 at org.grails.orm.hibernate.GrailsHibernateTemplate.doExecute(GrailsHibernateTemplate.java:305)
2023-05-04_15:51:40.66926 at org.grails.orm.hibernate.GrailsHibernateTemplate.execute(GrailsHibernateTemplate.java:241)
2023-05-04_15:51:40.66927 at org.grails.orm.hibernate.GrailsHibernateTemplate.execute(GrailsHibernateTemplate.java:120)
2023-05-04_15:51:40.66927 at org.grails.orm.hibernate.AbstractHibernateGormInstanceApi.performSave(AbstractHibernateGormInstanceApi.groovy:247)
2023-05-04_15:51:40.66928 at org.grails.orm.hibernate.AbstractHibernateGormInstanceApi.save(AbstractHibernateGormInstanceApi.groovy:164)
2023-05-04_15:51:40.66929 at org.grails.datastore.gorm.GormEntity$Trait$Helper.save(GormEntity.groovy:153)
2023-05-04_15:51:40.66930 at org.grails.datastore.gorm.GormEntity$Trait$Helper$save$10.call(Unknown Source)
2023-05-04_15:51:40.66930 at com.morpheus.backup.BackupResult.save(BackupResult.groovy)
2023-05-04_15:51:40.66930 at com.morpheus.backup.BackupResult.save(BackupResult.groovy)
2023-05-04_15:51:40.66930 at org.grails.datastore.gorm.GormEntity$save$0.call(Unknown Source)
2023-05-04_15:51:40.66931 at com.morpheus.backup.BackupService.updateBackupStatus(BackupService.groovy:2351)
2023-05-04_15:51:40.66932 at com.morpheus.backup.BackupService$updateBackupStatus$28.call(Unknown Source)
2023-05-04_15:51:40.66932 at com.morpheus.ApplianceJobService.executeApplianceJob(ApplianceJobService.groovy:819)
2023-05-04_15:51:40.66933 at jdk.internal.reflect.GeneratedMethodAccessor547.invoke(Unknown Source)
2023-05-04_15:51:40.66933 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
2023-05-04_15:51:40.66933 at java.base/java.lang.reflect.Method.invoke(Unknown Source)
2023-05-04_15:51:40.66934 at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:107)
2023-05-04_15:51:40.66934 at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:323)
2023-05-04_15:51:40.66934 at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:351)
2023-05-04_15:51:40.66934 at org.codehaus.groovy.runtime.callsite.PogoMetaClassSite.callCurrent(PogoMetaClassSite.java:61)
2023-05-04_15:51:40.66935 at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:203)
2023-05-04_15:51:40.66935 at com.morpheus.ApplianceJobService$_onApplianceJob_closure2.doCall(ApplianceJobService.groovy:277)
2023-05-04_15:51:40.66935 at com.morpheus.ApplianceJobService$_onApplianceJob_closure2.doCall(ApplianceJobService.groovy)
2023-05-04_15:51:40.66935 at jdk.internal.reflect.GeneratedMethodAccessor546.invoke(Unknown Source)
2023-05-04_15:51:40.66935 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
2023-05-04_15:51:40.66937 at java.base/java.lang.reflect.Method.invoke(Unknown Source)
2023-05-04_15:51:40.66937 at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:107)
2023-05-04_15:51:40.66937 at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:323)
2023-05-04_15:51:40.66937 at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:263)
2023-05-04_15:51:40.66938 at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1035)
2023-05-04_15:51:40.66938 at org.codehaus.groovy.runtime.callsite.PogoMetaClassSite.call(PogoMetaClassSite.java:38)
2023-05-04_15:51:40.66938 at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:130)
2023-05-04_15:51:40.66938 at com.morpheus.ApplianceJobService$_onApplianceJob_closure3.doCall(ApplianceJobService.groovy:344)
2023-05-04_15:51:40.66938 at jdk.internal.reflect.GeneratedMethodAccessor545.invoke(Unknown Source)
2023-05-04_15:51:40.66939 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
2023-05-04_15:51:40.66939 at java.base/java.lang.reflect.Method.invoke(Unknown Source)
2023-05-04_15:51:40.66939 at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:107)
2023-05-04_15:51:40.66939 at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:323)
2023-05-04_15:51:40.66940 at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:263)
2023-05-04_15:51:40.66941 at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1035)
2023-05-04_15:51:40.66941 at groovy.lang.Closure.call(Closure.java:412)
2023-05-04_15:51:40.66941 at groovy.lang.Closure.call(Closure.java:406)
2023-05-04_15:51:40.66942 at com.morpheus.util.BoundedExecutor$2.run(BoundedExecutor.java:47)
2023-05-04_15:51:40.66942 at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
2023-05-04_15:51:40.66942 at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
2023-05-04_15:51:40.66942 at java.base/java.lang.Thread.run(Unknown Source)
2023-05-04_15:51:40.66943 Caused by: org.hibernate.exception.DataException: could not execute batch
2023-05-04_15:51:40.66944 at org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:115)
2023-05-04_15:51:40.66944 at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:37)
2023-05-04_15:51:40.66945 at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:113)
2023-05-04_15:51:40.66945 at org.hibernate.engine.jdbc.batch.internal.BatchingBatch.performExecution(BatchingBatch.java:131)
2023-05-04_15:51:40.66946 at org.hibernate.engine.jdbc.batch.internal.BatchingBatch.doExecuteBatch(BatchingBatch.java:106)
2023-05-04_15:51:40.66947 at org.hibernate.engine.jdbc.batch.internal.AbstractBatchImpl.execute(AbstractBatchImpl.java:148)
2023-05-04_15:51:40.66947 at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.executeBatch(JdbcCoordinatorImpl.java:198)
2023-05-04_15:51:40.66948 at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:633)
2023-05-04_15:51:40.66948 at org.hibernate.engine.spi.ActionQueue.lambda$executeActions$1(ActionQueue.java:478)
2023-05-04_15:51:40.66948 at java.base/java.util.LinkedHashMap.forEach(Unknown Source)
2023-05-04_15:51:40.66949 at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:475)
2023-05-04_15:51:40.66949 at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:344)
2023-05-04_15:51:40.66949 at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:40)
2023-05-04_15:51:40.66950 at org.hibernate.event.service.internal.EventListenerGroupImpl.fireEventOnEachListener(EventListenerGroupImpl.java:107)
2023-05-04_15:51:40.66950 at org.hibernate.internal.SessionImpl.doFlush(SessionImpl.java:1391)
2023-05-04_15:51:40.66951 at org.hibernate.internal.SessionImpl.flush(SessionImpl.java:1378)
2023-05-04_15:51:40.66951 at org.grails.orm.hibernate.AbstractHibernateGormInstanceApi.flushSession(AbstractHibernateGormInstanceApi.groovy:285)
2023-05-04_15:51:40.66952 at jdk.internal.reflect.GeneratedMethodAccessor673.invoke(Unknown Source)
2023-05-04_15:51:40.66952 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
2023-05-04_15:51:40.66952 at java.base/java.lang.reflect.Method.invoke(Unknown Source)
2023-05-04_15:51:40.66952 at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:107)
2023-05-04_15:51:40.66954 at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:323)
2023-05-04_15:51:40.66954 at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1268)
2023-05-04_15:51:40.66954 at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1035)
2023-05-04_15:51:40.66955 at org.codehaus.groovy.runtime.InvokerHelper.invokePogoMethod(InvokerHelper.java:1029)
2023-05-04_15:51:40.66955 at org.codehaus.groovy.runtime.InvokerHelper.invokeMethod(InvokerHelper.java:1012)
2023-05-04_15:51:40.66955 at org.codehaus.groovy.runtime.InvokerHelper.invokeMethodSafe(InvokerHelper.java:101)
2023-05-04_15:51:40.66955 at org.grails.orm.hibernate.AbstractHibernateGormInstanceApi$_performSave_closure3.doCall(AbstractHibernateGormInstanceApi.groovy:250)
2023-05-04_15:51:40.66956 at jdk.internal.reflect.GeneratedMethodAccessor631.invoke(Unknown Source)
2023-05-04_15:51:40.66957 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
2023-05-04_15:51:40.66957 at java.base/java.lang.reflect.Method.invoke(Unknown Source)
2023-05-04_15:51:40.66957 at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:107)
2023-05-04_15:51:40.66958 at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:323)
2023-05-04_15:51:40.66958 at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:263)
2023-05-04_15:51:40.66958 at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1035)
2023-05-04_15:51:40.66958 at groovy.lang.Closure.call(Closure.java:412)
2023-05-04_15:51:40.66959 at org.codehaus.groovy.runtime.ConvertedClosure.invokeCustom(ConvertedClosure.java:50)
2023-05-04_15:51:40.66960 at org.codehaus.groovy.runtime.ConversionHandler.invoke(ConversionHandler.java:110)
2023-05-04_15:51:40.66960 at com.sun.proxy.$Proxy211.doInHibernate(Unknown Source)
2023-05-04_15:51:40.66960 at org.grails.orm.hibernate.GrailsHibernateTemplate.doExecute(GrailsHibernateTemplate.java:297)
2023-05-04_15:51:40.66961 … 45 common frames omitted
2023-05-04_15:51:40.66962 Caused by: java.sql.BatchUpdateException: Data truncation: Data too long for column ‘result_archive’ at row 1

[rajmartha]

5.4.16

[rajmartha]

yep, i have opened the case 70378.

[rajmartha]

Furtoher researching, Server that failed have a 12 hard drives, so the “result_archive” column have varchar 255 only, and the vaule ended up 288 chars. increasing then length might fix our issue temparerly, but not sure if this will stay same if we upgrade the morpehus in the future

[dgaharwar]

What version are you currently on?

[dgaharwar]

The value seems to be the same in our QA environment as well so if you can raise a case with our support for this then our dev team can take a look.