We have a 4.0.2 environment that we are upgrading to 4.5.3. 4.0.2 RBPM
was on MySQL (legacy from 3.7). During the install process for userapp,
I chose New Database (MS SQL 2012) on startup. On 1st startup, I see it
going through and creating database tables, indexes, etc. After a fair
amount of liquibase work, I get this...


Code:
--------------------
2016-03-21 14:58:50,354 [localhost-startStop-1] INFO liquibase- liquibase: Reading from [dbo].[DATABASECHANGELOG]
2016-03-21 14:58:50,386 [localhost-startStop-1] INFO liquibase- liquibase: Reading from [dbo].[DATABASECHANGELOG]
2016-03-21 14:58:50,666 [localhost-startStop-1] INFO liquibase- liquibase: Successfully released change log lock
2016-03-21 14:58:50,666 [localhost-startStop-1] ERROR liquibase- liquibase: Could not release lock
liquibase.exception.LockException: liquibase.exception.UnexpectedLiquibaseException: liquibase.exception.DatabaseException: java.sql.SQLException: Connection has already been closed.
at liquibase.lockservice.StandardLockService.releaseL ock(StandardLockService.java:234)
at liquibase.Liquibase.update(Liquibase.java:206)
at liquibase.Liquibase.update(Liquibase.java:181)
at com.novell.soa.persist.DatabaseSchemaUpdate.update Schema(DatabaseSchemaUpdate.java:432)
at com.novell.soa.persist.DatabaseSchemaUpdate.valida teDatabaseSchema(DatabaseSchemaUpdate.java:238)
at com.sssw.fw.servlet.EboBootServlet.init(EboBootSer vlet.java:98)
at com.sssw.portal.servlet.EboPortalBootServlet.init( EboPortalBootServlet.java:59)
at javax.servlet.GenericServlet.init(GenericServlet.j ava:158)
at org.apache.catalina.core.StandardWrapper.initServl et(StandardWrapper.java:1284)
at org.apache.catalina.core.StandardWrapper.loadServl et(StandardWrapper.java:1197)
at org.apache.catalina.core.StandardWrapper.load(Stan dardWrapper.java:1087)
at org.apache.catalina.core.StandardContext.loadOnSta rtup(StandardContext.java:5229)
at org.apache.catalina.core.StandardContext.startInte rnal(StandardContext.java:5516)
at org.apache.catalina.util.LifecycleBase.start(Lifec ycleBase.java:150)
at org.apache.catalina.core.ContainerBase.addChildInt ernal(ContainerBase.java:901)
at org.apache.catalina.core.ContainerBase.addChild(Co ntainerBase.java:877)
at org.apache.catalina.core.StandardHost.addChild(Sta ndardHost.java:649)
at org.apache.catalina.startup.HostConfig.deployWAR(H ostConfig.java:1083)
at org.apache.catalina.startup.HostConfig$DeployWar.r un(HostConfig.java:1880)
at java.util.concurrent.Executors$RunnableAdapter.cal l(Unknown Source)
at java.util.concurrent.FutureTask.run(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor.runWorker( Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run (Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: liquibase.exception.UnexpectedLiquibaseException: liquibase.exception.DatabaseException: java.sql.SQLException: Connection has already been closed.
at liquibase.database.core.MSSQLDatabase.getDefaultCa talogName(MSSQLDatabase.java:170)
at liquibase.database.AbstractJdbcDatabase.getLiquiba seCatalogName(AbstractJdbcDatabase.java:636)
at liquibase.snapshot.SnapshotGeneratorFactory.hasDat abaseChangeLogLockTable(SnapshotGeneratorFactory.j ava:169)
at liquibase.lockservice.StandardLockService.hasDatab aseChangeLogLockTable(StandardLockService.java:134 )
at liquibase.lockservice.StandardLockService.releaseL ock(StandardLockService.java:224)
... 23 more
Caused by: liquibase.exception.DatabaseException: java.sql.SQLException: Connection has already been closed.
at liquibase.database.jvm.JdbcConnection.getCatalog(J dbcConnection.java:172)
at liquibase.database.core.MSSQLDatabase.getDefaultCa talogName(MSSQLDatabase.java:168)
... 27 more
Caused by: java.sql.SQLException: Connection has already been closed.
at org.apache.tomcat.jdbc.pool.ProxyConnection.invoke (ProxyConnection.java:117)

--------------------


I had a similar issue in our dev/test env. I was able to get past it
with running this on the sql server each time it occurred and then
restarting tomcat (maybe 3-4):

Code:
--------------------

UPDATE DATABASECHANGELOGLOCK
SET locked=0, lockgranted=null, lockedby=null
WHERE id=1;

--------------------


I get past it, and it eventually says the schema is fine. I am now
getting what is below. A few things about the environment, the
IdentityApps are pointed at a RW replica of the master server.



Code:
--------------------
2016-03-21 15:32:01,301 [localhost-startStop-1] INFO com.novell.idm.nrf.service.RoleManagerService- [RBPM] [Role_Request] Requested by cn=admin,o=uww, Target DN: cn=ZZZ,o=XXX, Source D
N:cn=roleManager,cn=System,cn=Level20,cn=RoleDefs, cn=RoleConfig,cn=AppConfig,cn=UserApplication,cn=Y YY,o=XXX, Request DN:cn=20160321153201-3078b1605bca4c928de0269087f61066-0,cn=Req
uests,cn=RoleConfig,cn=AppConfig,cn=UserApplicatio n,cn=YYY,o=XXX, Request Category: 15, Request Status: 0, Original Request Status: 0, Correlation ID: UserApp#RoleRequest#6771495f-
81a0-4bd0-bb7f-ef1d17ca376f

2016-03-21 15:32:01,301 [localhost-startStop-1] INFO com.novell.idm.security.authorization.service.Auth orizationManagerService- [RBPM] [Delete_Delegated_Administrator] Initiated by com.nov
ell.idm.security.authorization.service.Authorizati onManagerService; Delegated Administrator DN: cn=ZZZ,o=XXX

javax.naming.CommunicationException: [LDAP: error code 2 - Unrecognized extended operation]; remaining name ''
at com.sun.jndi.ldap.LdapCtx.mapErrorCode(Unknown Source)
at com.sun.jndi.ldap.LdapCtx.processReturnCode(Unknow n Source)
at com.sun.jndi.ldap.LdapCtx.processReturnCode(Unknow n Source)
at com.sun.jndi.ldap.LdapCtx.extendedOperation(Unknow n Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Nativ e Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknow n Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Un known Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at com.sssw.fw.directory.realm.impl.jndildap.EboLdapC ontextProxyHandler.invokeMethod(EboLdapContextProx yHandler.java:145)
at com.sssw.fw.directory.realm.impl.jndildap.EboLdapC ontextProxyHandler.invokeMethod(EboLdapContextProx yHandler.java:152)

--------------------


After trying to logon I get ...

Code:
--------------------
2016-03-21 16:08:59,743 [ajp-bio-8009-exec-2] INFO com.novell.pwdmgt.util.PasswordHelper- [RBPM] [Login_Success] cn=ZZZ,o=XXX successfully logged in.
2016-03-21 16:08:59,747 [ajp-bio-8009-exec-2] ERROR com.novell.afw.portal.util.NavigationHelper- [RBPM] Error initializing com.sssw.fw.security.core.EboSecurityManager.
com.sssw.fw.exception.EboUnrecoverableSystemExcept ion: Error initializing com.sssw.fw.security.core.EboSecurityManager.
at com.sssw.fw.security.core.EboSecurityManager.<clin it>(EboSecurityManager.java:117)
at com.sssw.portal.servlet.EboPortalBootServlet.init( EboPortalBootServlet.java:102)
at javax.servlet.GenericServlet.init(GenericServlet.j ava:158)
at org.apache.catalina.core.StandardWrapper.initServl et(StandardWrapper.java:1284)
at org.apache.catalina.core.StandardWrapper.loadServl et(StandardWrapper.java:1197)
at org.apache.catalina.core.StandardWrapper.load(Stan dardWrapper.java:1087)
at org.apache.catalina.core.StandardContext.loadOnSta rtup(StandardContext.java:5229)
at org.apache.catalina.core.StandardContext.startInte rnal(StandardContext.java:5516)
at org.apache.catalina.util.LifecycleBase.start(Lifec ycleBase.java:150)
at org.apache.catalina.core.ContainerBase.addChildInt ernal(ContainerBase.java:901)
at org.apache.catalina.core.ContainerBase.addChild(Co ntainerBase.java:877)
at org.apache.catalina.core.StandardHost.addChild(Sta ndardHost.java:649)
at org.apache.catalina.startup.HostConfig.deployWAR(H ostConfig.java:1083)
at org.apache.catalina.startup.HostConfig$DeployWar.r un(HostConfig.java:1880)
at java.util.concurrent.Executors$RunnableAdapter.cal l(Unknown Source)
at java.util.concurrent.FutureTask.run(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor.runWorker( Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run (Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: com.sssw.fw.exception.EboUnrecoverableSystemExcept ion: Access right permission PROTECT is not defined for element type LocksmithElementType.
at com.sssw.fw.security.core.EboSecurityManager.getEl ementAclForPermission(EboSecurityManager.java:1271 )
at com.sssw.fw.security.core.EboSecurityManager.setLo cksmith(EboSecurityManager.java:298)
at com.sssw.fw.security.core.EboSecurityManager.initi alize(EboSecurityManager.java:151)
at com.sssw.fw.security.core.EboSecurityManager.<clin it>(EboSecurityManager.java:115)
... 18 more
2016-03-21 16:08:59,916 [ajp-bio-8009-exec-2] ERROR com.novell.afw.portal.util.NavigationHelper- [RBPM] Error initializing com.sssw.fw.security.core.EboSecurityManager.
com.sssw.fw.exception.EboUnrecoverableSystemExcept ion: Error initializing com.sssw.fw.security.core.EboSecurityManager.
at com.sssw.fw.security.core.EboSecurityManager.<clin it>(EboSecurityManager.java:117)
at com.sssw.portal.servlet.EboPortalBootServlet.init( EboPortalBootServlet.java:102)
at javax.servlet.GenericServlet.init(GenericServlet.j ava:158)

--------------------


--
schwoerb
------------------------------------------------------------------------
schwoerb's Profile: https://forums.netiq.com/member.php?userid=2338
View this thread: https://forums.netiq.com/showthread.php?t=55584