Hello,
I am trying to recover the index (to test if Alfresco will go after a crash (simulation if the backup-index is not up to date)) but it couldn't. It try to start, work two hours, DB generate a problem, and Alfresco retry.
I am using Alfresco 3.0 and Ingres DB 9.2.
The contenStore has 11732 files/directories (ls -R * | wc -w).
I think that the problem come from my configuration of Ingres. Before crash, DB log indicates a problem with the Internal error in PSF. I have try to increase the value of the PSF from 17'000'000 bytes to 40'000'000 bytes but nothing change. (I have used the script gived by Ingres for the configuration : http://wiki.alfresco.com/wiki/Installing_Alfresco_Labs_3b_and_Ingres)
DB Ingres : error.log
Alfresco log :
If a GURU Ingres come here… thanks for your help
I am trying to recover the index (to test if Alfresco will go after a crash (simulation if the backup-index is not up to date)) but it couldn't. It try to start, work two hours, DB generate a problem, and Alfresco retry.
I am using Alfresco 3.0 and Ingres DB 9.2.
The contenStore has 11732 files/directories (ls -R * | wc -w).
I think that the problem come from my configuration of Ingres. Before crash, DB log indicates a problem with the Internal error in PSF. I have try to increase the value of the PSF from 17'000'000 bytes to 40'000'000 bytes but nothing change. (I have used the script gived by Ingres for the configuration : http://wiki.alfresco.com/wiki/Installing_Alfresco_Labs_3b_and_Ingres)
DB Ingres : error.log
LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Mon Aug 10 10:52:49 2009 E_PS0004_EXCEPTION Exception occurred in PSFLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: An error occurred in the following session:LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: >>>>>Session 0310C100:49544080<<<<<LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: DB Name: alfresco (Owned by: ingres )LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: User: ingres (ingres )LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: User Name at Session Startup: ingresLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Terminal: batchLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Group Id:LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Role Id:LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Application Code: 00000000 Current Facility: PSF (00000005)LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client user: ingresLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client host: localhost.localdomainLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client tty: localhost.localdomainLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client pid: 5924LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client connection target: alfrescoLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client information: user='ingres',host='localhost.localdomain',tty='localhost.localdomain',pid=5924,conn='alfresco'LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Description:LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Query:open ~Q cursor for JDBC_STMT_c_0 for readonly using ~V , ~V , ~V , ~V, ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V , ~V…LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]:localhos::[53860 , 0310c100]: Mon Aug 10 10:52:49 2009 Segmentation Violation (SIGSEGV) @PC 081675eeESP 02f19000 EBP 02f19008 ESI 04bf0688 EDI 02d72f84EAX 00000000 EBX 08796e68 ECX 00000000 EDX 00000000DS 0000007b ES 0000007b SS 0000007b———–BEGIN STACK TRACE————0: 2f19008 iidbms(pst_prmsub+0x5) [0x81675ee](310fa2c 310fd94 2d73088 4bdf47c)1: 2f190d8 iidbms(pst_prmsub+0x12d) [0x8167716](310fa2c 310fd94 2d72f84 4bdf47c)…———–END STACK TRACE———-LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Mon Aug 10 10:52:49 2009 E_PS0002_INTERNAL_ERROR Internal error in PSFLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Mon Aug 10 10:52:49 2009 E_PS0002_INTERNAL_ERROR Internal error in PSFLOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Mon Aug 10 10:52:49 2009 E_SC0215_PSF_ERROR Error returned by PSF.LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Mon Aug 10 10:52:49 2009 E_SC0220_SESSION_ERROR_MAX Too many errors for this session.LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: PQuery: open ~Q cursor for JDBC_STMT_c_0 for readonly using ~V , ~V , ~V , ~V , ~V , ~V ,Alfresco log :
09:00:26,569 User:System INFO [node.index.FullIndexRecoveryComponent] Récupération de l'index débutée : 977 transactions.09:00:27,405 User:System INFO [node.index.FullIndexRecoveryComponent] 10 % complete.09:00:27,410 User:System INFO [node.index.FullIndexRecoveryComponent] 20 % complete.09:00:27,507 User:System INFO [node.index.FullIndexRecoveryComponent] 30 % complete.09:00:27,612 User:System INFO [node.index.FullIndexRecoveryComponent] 40 % complete.09:00:27,650 User:System INFO [node.index.FullIndexRecoveryComponent] 50 % complete.09:00:27,657 User:System INFO [node.index.FullIndexRecoveryComponent] 60 % complete.09:00:27,658 User:System INFO [node.index.FullIndexRecoveryComponent] 70 % complete.09:00:27,663 User:System INFO [node.index.FullIndexRecoveryComponent] 80 % complete.09:00:27,669 User:System INFO [node.index.FullIndexRecoveryComponent] 90 % complete.09:00:27,671 User:System INFO [node.index.FullIndexRecoveryComponent] 100 % complete.10:52:49,698 User:System ERROR [hibernate.transaction.JDBCTransaction] JDBC rollback failedcom.ingres.gcf.util.SqlEx: Server aborted connection. at com.ingres.gcf.util.SqlEx.get(Unknown Source) at com.ingres.gcf.dam.MsgIn.serverDisconnect(Unknown Source) at com.ingres.gcf.dam.MsgIn.receive(Unknown Source) at com.ingres.gcf.jdbc.DrvObj.readResults(Unknown Source) at com.ingres.gcf.jdbc.JdbcConn.rollback(Unknown Source) at org.apache.commons.dbcp.DelegatingConnection.rollback(DelegatingConnection.java:265) at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.rollback(PoolingDataSource.java:288) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:183) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:162) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:594) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:753) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:730) at org.springframework.transaction.interceptor.TransactionAspectSupport.completeTransactionAfterThrowing(TransactionAspectSupport.java:332) at org.alfresco.util.transaction.SpringAwareUserTransaction.completeTransactionAfterThrowing(SpringAwareUserTransaction.java:561) at org.alfresco.util.transaction.SpringAwareUserTransaction.rollback(SpringAwareUserTransaction.java:531) at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388) at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:246) at org.alfresco.repo.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:256) at org.alfresco.repo.node.index.IndexRecoveryBootstrapBean.onBootstrap(IndexRecoveryBootstrapBean.java:35) at org.alfresco.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:62) at org.springframework.context.event.SimpleApplicationEventMulticaster$1.run(SimpleApplicationEventMulticaster.java:77) at org.springframework.core.task.SyncTaskExecutor.execute(SyncTaskExecutor.java:49) at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:75) at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:246) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:355) at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:244) at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:187) at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3843) at org.apache.catalina.core.StandardContext.start(StandardContext.java:4342) at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791) at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771) at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:525) at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:830) at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:719) at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:490) at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1149) at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311) at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117) at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053) at org.apache.catalina.core.StandardHost.start(StandardHost.java:719) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443) at org.apache.catalina.core.StandardService.start(StandardService.java:516) at org.apache.catalina.core.StandardServer.start(StandardServer.java:710) at org.apache.catalina.startup.Catalina.start(Catalina.java:578) 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:597) at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288) at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)10:52:49,804 User:System ERROR [repo.transaction.RetryingTransactionHelper] Rollback failure. Normal retry behaviour will resume.org.springframework.jdbc.UncategorizedSQLException: Hibernate flushing: Cannot release connection; uncategorized SQLException for SQL [???]; SQL state [null]; error code [0]; Already closed.; nested exception is java.sql.SQLException: Already closed.Caused by: java.sql.SQLException: Already closed. at org.apache.commons.dbcp.PoolableConnection.close(PoolableConnection.java:77) at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.close(PoolingDataSource.java:180) at org.springframework.orm.hibernate3.LocalDataSourceConnectionProvider.closeConnection(LocalDataSourceConnectionProvider.java:95) at org.hibernate.jdbc.ConnectionManager.closeConnection(ConnectionManager.java:451) at org.hibernate.jdbc.ConnectionManager.aggressiveRelease(ConnectionManager.java:406) at org.hibernate.jdbc.ConnectionManager.afterTransaction(ConnectionManager.java:293) at org.hibernate.jdbc.JDBCContext.afterTransactionCompletion(JDBCContext.java:225) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:174) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:594) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:753) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:730) at org.springframework.transaction.interceptor.TransactionAspectSupport.completeTransactionAfterThrowing(TransactionAspectSupport.java:332) at org.alfresco.util.transaction.SpringAwareUserTransaction.completeTransactionAfterThrowing(SpringAwareUserTransaction.java:561) at org.alfresco.util.transaction.SpringAwareUserTransaction.rollback(SpringAwareUserTransaction.java:531) at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388) at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:246) at org.alfresco.repo.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:256) at org.alfresco.repo.node.index.IndexRecoveryBootstrapBean.onBootstrap(IndexRecoveryBootstrapBean.java:35) at org.alfresco.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:62) at org.springframework.context.event.SimpleApplicationEventMulticaster$1.run(SimpleApplicationEventMulticaster.java:77) at org.springframework.core.task.SyncTaskExecutor.execute(SyncTaskExecutor.java:49) at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:75) at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:246) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:355) at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:244) at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:187) at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3843) at org.apache.catalina.core.StandardContext.start(StandardContext.java:4342) at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791) at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771) at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:525) at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:830) at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:719) at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:490) at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1149) at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311) at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053) at org.apache.catalina.core.StandardHost.start(StandardHost.java:719) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443) at org.apache.catalina.core.StandardService.start(StandardService.java:516) at org.apache.catalina.core.StandardServer.start(StandardServer.java:710) at org.apache.catalina.startup.Catalina.start(Catalina.java:578) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288) at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)10:52:51,956 User:System INFO [node.index.FullIndexRecoveryComponent] Récupération de l'index débutée : 977 transactions.10:52:52,572 User:System INFO [node.index.FullIndexRecoveryComponent] 10 % complete.10:52:52,574 User:System INFO [node.index.FullIndexRecoveryComponent] 20 % complete.If a GURU Ingres come here… thanks for your help
