topic Re: Exception: Data too long for column 'serializable_valu in Alfresco Archive

Exception: Data too long for column 'serializable_valu

bparis — Wed, 07 May 2008 09:56:46 GMT

Hi,Preamble: the following problem does NOT appear on our test server running alfresco 2.1.2 and mySQL version 5.0.37-log.We use alfresco 2.0.1 on RHEL with mysql Ver 14.12 Distrib 5.0.41 on our production system.We have been modified the LDAPAuthenticationComponentImpl in order to use our LDAP serv

Re: Exception: Data too long for column 'serializable_valu

bparis — Wed, 07 May 2008 15:12:31 GMT

it's seems to me Alfresco creates a data field very too big to simply fit into the serialized_value column (type blob, which is 256k as I undestand in mySQL).
Is it possible ?

bernard

Re: Exception: Data too long for column 'serializable_valu

bparis — Thu, 15 May 2008 12:59:07 GMT

To make my problem clearer here is a simple example of the problem with alfreco 2.1.2.
This code runs OK for lot of users but fails for some others, I really don't unserstand…
It can succeeds with 10 or more groups, or fails with 2 groups, …. :evil:
I get an Exception later (the whole loop complete successfully) when it's time to commit. Could some one explain me what to do :

the simple code:


          Vector<String> user_authorities = new Vector<String>();
           user_authorities.add("GROUP_str-aust");
           user_authorities.add("GROUP_str-sc");
           user_authorities.add("GROUP_str-atuc");
           …..
           for (String an_auth: user_authorities ) {
                logger.debug("\tnew group to add into: " + an_auth );
                authorityService.addAuthority(an_auth, userName);
            }‍‍‍‍‍‍‍‍‍‍

and the result:
The following exception arrives AFTER the loop is completed if the Vecotr is too long (about more than only some elements)

2008-05-15 14:32:40,669 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:560) - ————————————
2008-05-15 14:32:40,670 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_str-aust
2008-05-15 14:32:40,707 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_str-sc
2008-05-15 14:32:40,746 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_str-atuc
2008-05-15 14:32:40,879 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_str-biol
2008-05-15 14:32:40,908 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_str-bani
2008-05-15 14:32:40,972 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_str-pror
2008-05-15 14:32:41,051 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_pbl-personnel
2008-05-15 14:32:41,113 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_pbl-alumni
2008-05-15 14:32:42,182 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-bex
2008-05-15 14:32:42,611 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-bologn
2008-05-15 14:32:42,948 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-buda
2008-05-15 14:32:42,980 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-cac
2008-05-15 14:32:43,005 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-cadm
2008-05-15 14:32:43,034 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-cefo
2008-05-15 14:32:43,166 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-coda
2008-05-15 14:32:43,192 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-cofc
2008-05-15 14:32:43,218 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-crect
2008-05-15 14:32:43,245 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-lyc
2008-05-15 14:32:43,363 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_mnd-pror
2008-05-15 14:32:43,389 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_grp-bi
2008-05-15 14:32:43,414 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_grp-dic-revue-presse
2008-05-15 14:32:43,441 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_prj-n-ucl-conseil-rectoral
2008-05-15 14:32:43,559 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_prj-n-ucl-etudes
2008-05-15 14:32:43,583 DEBUG (LDAPAuthenticationComponentImpl_ucl.java:565) - new group to add into: GROUP_prj-n-ucl-gt
2008-05-15 14:32:43,793 ERROR (StandardWrapperValve.java:250) - Servlet.service() for servlet Faces Servlet threw exception
java.sql.BatchUpdateException: Data truncation: Data too long for column 'serializable_value' at row 1
        at com.mysql.jdbc.PreparedStatement.executeBatchSerially(PreparedStatement.java:1213)
        at com.mysql.jdbc.PreparedStatement.executeBatch(PreparedStatement.java:912)
        at org.apache.commons.dbcp.DelegatingStatement.executeBatch(DelegatingStatement.java:294)
        at org.hibernate.jdbc.BatchingBatcher.doExecuteBatch(BatchingBatcher.java:48)
        at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:242)
        at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:92)
        at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:87)
        at org.hibernate.jdbc.AbstractBatcher.prepareBatchStatement(AbstractBatcher.java:218)
        at org.hibernate.persister.collection.AbstractCollectionPersister.insertRows(AbstractCollectionPersister.java:1341)
        at org.hibernate.action.CollectionUpdateAction.execute(CollectionUpdateAction.java:56)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:248)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:232)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:142)
        at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)
        at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
        at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1000)
        at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:338)
        at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:106)
        at org.springframework.orm.hibernate3.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:562)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:654)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:624)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:307)
        at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:117)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:176)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:210)
        at $Proxy19.authenticate(Unknown Source)
        at org.alfresco.web.bean.LoginBean.login(LoginBean.java:248)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.apache.myfaces.el.MethodBindingImpl.invoke(MethodBindingImpl.java:132)
        at org.apache.myfaces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:61)
        at javax.faces.component.UICommand.broadcast(UICommand.java:109)
        at javax.faces.component.UIViewRoot._broadcastForPhase(UIViewRoot.java:97)

…….

Re: Exception: Data too long for column 'serializable_valu

andy — Mon, 28 Jul 2008 09:43:56 GMT

Hi

This is a known issue and can be tracked on Jira.

Andy

Re: Exception: Data too long for column 'serializable_valu

ymartin — Mon, 28 Jul 2008 10:10:16 GMT

Hello Andy,
I have just setup synchronisation with a large LDAP (14430 users and 2945 groups) and the group synchronisation fails with the same error:


Caused by: org.alfresco.service.cmr.view.ImporterException: Failed to import package at line 16914; column 29 due to error: JDBC exception on Hibernate data 
access; nested exception is org.hibernate.exception.GenericJDBCException: Could not execute JDBC batch update
at org.alfresco.repo.importer.view.ViewParser.parse(ViewParser.java:190)
[…]
Caused by: org.springframework.orm.hibernate3.HibernateJdbcException: JDBC exception on Hibernate data access; nested exception is org.hibernate.exception.Ge
nericJDBCException: Could not execute JDBC batch update
        at org.springframework.orm.hibernate3.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:636)
[…]
Caused by: java.sql.BatchUpdateException: Data truncation: Data too long for column 'serializable_value' at row 1
        at com.mysql.jdbc.ServerPreparedStatement.executeBatch(ServerPreparedStatement.java:657)
‍‍‍‍‍‍‍‍‍‍‍‍

And the users synchronisation fails with MySQL lock errors:


12:04:53,495 ERROR [org.quartz.core.ErrorLogger] Job (DEFAULT.ldapPeopleJobDetail threw an exception.
org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: org.alfresco.repo.importer.ExportSourceImporterException: Failed to import]
   at org.quartz.core.JobRunShell.run(JobRunShell.java:213)
[…]
Caused by: org.alfresco.service.cmr.view.ImporterException: Failed to import package at line 12; column 16 due to error: could not update: [org.alfresco.repo.domain.hibernate.ChildAssocImpl#25104]; nested exception is org.hibernate.exception.LockAcquisitionException: could not update: [org.alfresco.repo.domain.hibernate.ChildAssocImpl#25104]
   at org.alfresco.repo.importer.view.ViewParser.parse(ViewParser.java:190)
[…]
Caused by: org.springframework.dao.CannotAcquireLockException: could not update: [org.alfresco.repo.domain.hibernate.ChildAssocImpl#25104]; nested exception is org.hibernate.exception.LockAcquisitionException: could not update: [org.alfresco.repo.domain.hibernate.ChildAssocImpl#25104]
   at org.springframework.orm.hibernate3.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:633)
[…]
Caused by: org.hibernate.exception.LockAcquisitionException: could not update: [org.alfresco.repo.domain.hibernate.ChildAssocImpl#25104]
   at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:82)
[…]
Caused by: com.mysql.jdbc.exceptions.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction
   at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:941)
   at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2870)
   at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1573)
   at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1160)
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Then the Tomcat server does not shutdown properly… the process must be killed.

May you please post references to JIRA tickets concerning these LDAP synchrosations issues ? Thank you in advance
–
Yves

Re: Exception: Data too long for column 'serializable_valu

ymartin — Mon, 28 Jul 2008 10:55:15 GMT

MySQL lock errors come from a mistake in cron expression to trigger user import job.
Forget it. Sorry.
–
Yves

Re: Exception: Data too long for column 'serializable_valu

ymartin — Tue, 29 Jul 2008 06:47:33 GMT

I have found the following ticket that match exactly that group import issue: https://issues.alfresco.com/jira/browse/AR-1391
Is there any known work-around to get the import work ?