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 PSF
LOCALHOST_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: ingres
LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Terminal: batch
LOCALHOST_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: ingres
LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client host: localhost.localdomain
LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client tty: localhost.localdomain
LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client pid: 5924
LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Client connection target: alfresco
LOCALHOST_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 081675ee
ESP 02f19000 EBP 02f19008 ESI 04bf0688 EDI 02d72f84
EAX 00000000 EBX 08796e68 ECX 00000000 EDX 00000000
DS 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 PSF
LOCALHOST_LOCALDOM::[53860 , 5841 , 0310c100]: Mon Aug 10 10:52:49 2009 E_PS0002_INTERNAL_ERROR Internal error in PSF
LOCALHOST_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 failed
com.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
