[opencms-dev] problem upgrading opencms 7.0.1 to 7.0.3

Thu Feb 7 19:41:39 CET 2008

I am having one heck of a time trying to upgrade OpenCMS
7.0.1 to 7.0.3. Here is my setup:
1 Sun machine with JDK 1.5 and Tomcat 5.5 running opencms
1 Sun machine running Oracle 10

Setup of 7.0.1 was a snap, but the upgrade seems to not be
working. It fails on step 5b of the update procedure. The
error output is:
1: OpenCms WEB-INF path: "/opt/opencms/opencms/WEB-INF/".
2: OpenCms property file:
"/opt/opencms/opencms/WEB-INF/config/opencms.properties".
3:
4: java.lang.NoSuchMethodError:
org.opencms.db.CmsSecurityManager.hasPermissions(Lorg/opencms/db/CmsDbContext;Lorg/opencms/file/CmsResource;Lorg/opencms/security/CmsPermissionSet;ZLorg/opencms/file/CmsResourceFilter;)I
5: at
org.opencms.db.CmsDriverManager.filterPermissions(CmsDriverManager.java:8627)
6: at
org.opencms.db.CmsDriverManager.readResourcesWithProperty(CmsDriverManager.java:6664)
7: at
org.opencms.db.CmsSecurityManager.readResourcesWithProperty(CmsSecurityManager.java:4067)
8: at
org.opencms.file.CmsObject.readResourcesWithProperty(CmsObject.java:3589)
9: at
org.opencms.file.CmsObject.readResourcesWithProperty(CmsObject.java:3565)
10: at
org.opencms.file.CmsObject.readResourcesWithProperty(CmsObject.java:3547)
11: at
org.opencms.staticexport.CmsStaticExportManager.setExportnames(CmsStaticExportManager.java:2487)
12: at
org.opencms.staticexport.CmsStaticExportManager.initialize(CmsStaticExportManager.java:1464)
13: at
org.opencms.main.OpenCmsCore.initConfiguration(OpenCmsCore.java:1164)
14: at
org.opencms.main.OpenCmsCore.upgradeRunlevel(OpenCmsCore.java:1692)
15: at org.opencms.main.CmsShell.<init>(CmsShell.java:450)
16: at
org.opencms.setup.CmsUpdateThread.run(CmsUpdateThread.java:150)
17: ${user}@${project}># Turn echo on
18: ${user}@${project}>java.lang.NullPointerException
19: at
org.opencms.main.CmsShell.executeCommand(CmsShell.java:743)
20: at
org.opencms.main.CmsShell.executeCommands(CmsShell.java:818)
21: at org.opencms.main.CmsShell.start(CmsShell.java:646)
22: at
org.opencms.setup.CmsUpdateThread.run(CmsUpdateThread.java:187)
23: java.lang.NullPointerException
24: at org.opencms.main.CmsShell.exit(CmsShell.java:557)
25: at
org.opencms.setup.CmsUpdateThread.kill(CmsUpdateThread.java:114)
26: at
org.opencms.setup.CmsUpdateThread.run(CmsUpdateThread.java:198)
27:
28:
29: Shutting down OpenCms, version 7.0.3 in web application
"opencms"
30: Shutdown completed, total uptime was 00:01:17.
31:

In opencms.log, I see the following errors:
04 Feb 2008 17:03:16,974 DEBUG
[encms.db.generic.CmsSqlManager: 179] Already closed
java.sql.SQLException: Already closed
at
org.apache.commons.dbcp.PoolablePreparedStatement.close(PoolablePreparedStatement.java:76)
at
org.apache.commons.dbcp.DelegatingStatement.close(DelegatingStatement.java:168)
at
org.opencms.db.generic.CmsSqlManager.closeAll(CmsSqlManager.java:176)
at
org.opencms.db.generic.CmsVfsDriver.readPropertyObject(CmsVfsDriver.java:1680)
at
org.opencms.db.CmsDriverManager.readPropertyObject(CmsDriverManager.java:6283)
at
org.opencms.db.generic.CmsUserDriver.internalCreateOrgUnitFromResource(CmsUserDriver.java:2225)
at
org.opencms.db.generic.CmsUserDriver.readOrganizationalUnit(CmsUserDriver.java:1304)
at
org.opencms.db.generic.CmsUserDriver.createRootOrganizationalUnit(CmsUserDriver.java:398)
at
org.opencms.db.CmsDriverManager.init(CmsDriverManager.java:4368)
at
org.opencms.db.CmsSecurityManager.init(CmsSecurityManager.java:2711)
at
org.opencms.db.CmsSecurityManager.newInstance(CmsSecurityManager.java:156)
at
org.opencms.main.OpenCmsCore.initConfiguration(OpenCmsCore.java:1117)
at
org.opencms.main.OpenCmsCore.upgradeRunlevel(OpenCmsCore.java:1692)
at org.opencms.main.CmsShell.<init>(CmsShell.java:450)
at
org.opencms.setup.CmsUpdateThread.run(CmsUpdateThread.java:150)
04 Feb 2008 17:03:16,977 INFO
[.opencms.db.CmsSecurityManager:2713] . Driver manager init
: phase 4 ok - finished
04 Feb 2008 17:03:16,985 INFO
[.opencms.db.CmsSecurityManager:2745] . Security manager
init: ok - finished
04 Feb 2008 17:03:17,015 INFO
[uration.CmsSystemConfiguration:1673] . Session Storage
Prov : org.opencms.main.CmsDefaultSessionStorageProvider
instanciated
04 Feb 2008 17:03:17,046 DEBUG
[encms.db.generic.CmsSqlManager: 179] Already closed
java.sql.SQLException: Already closed
at
org.apache.commons.dbcp.PoolablePreparedStatement.close(PoolablePreparedStatement.java:76)
at
org.apache.commons.dbcp.DelegatingStatement.close(DelegatingStatement.java:168)
at
org.opencms.db.generic.CmsSqlManager.closeAll(CmsSqlManager.java:176)
at
org.opencms.db.generic.CmsUserDriver.readGroupsOfUser(CmsUserDriver.java:1289)
at
org.opencms.db.CmsDriverManager.getGroupsOfUser(CmsDriverManager.java:3521)
at
org.opencms.db.CmsSecurityManager.hasRole(CmsSecurityManager.java:2378)
at
org.opencms.db.CmsSecurityManager.checkRole(CmsSecurityManager.java:524)
at
org.opencms.db.CmsSecurityManager.checkRole(CmsSecurityManager.java:548)
at
org.opencms.security.CmsRoleManager.checkRole(CmsRoleManager.java:98)
at
org.opencms.scheduler.CmsScheduleManager.initialize(CmsScheduleManager.java:242)
at
org.opencms.main.OpenCmsCore.initConfiguration(OpenCmsCore.java:1154)
at
org.opencms.main.OpenCmsCore.upgradeRunlevel(OpenCmsCore.java:1692)
at org.opencms.main.CmsShell.<init>(CmsShell.java:450)
at
org.opencms.setup.CmsUpdateThread.run(CmsUpdateThread.java:150)

>From snoop output, I see the following:
...................SELECT * FROM CMS_GROUPS WHERE
CMS_GROUPS.GROUP_NAME=:1 AND
CMS_GROUPS.GROUP_OU=:2....................*.......g...............g....RoleRootAdmins./.O............l......&....N.xl...$...]..M.....$......g..$.....GROUP_ID........$......g..$.....PARENT_GROUP_ID................g....
.

GROUP_NAME................g........GROUP_DESCRIPTION......&..................GROUP_FLAGS................g........GROUP_OU.......xl...$..........o...................7<<..................................................
................
....$longnumber.RoleRootAdmins.A system role
group....VM./.......{........ ...............ORA-01403: no
data found
.k.........^...h......
.....................
.................*.......g...............g....Administrators./................
....$longernumber.Administrators1%(key.GUI_DEFAULTGROUP_ROOT_ADMINS_DESCRIPTION_0)...../....o.................{........
...............ORA-01403: no data found
.k.........^...h......
.....................
.................*.......g...............g....Administrators./................
....$longnumber.Administrators1%(key.GUI_DEFAULTGROUP_ROOT_ADMINS_DESCRIPTION_0)...../....o.................{........
...............ORA-01403: no data found
.b.........^...h......
.....................
.................*.......g...............g....Users./................
....$longnumber.Users0%(key.GUI_DEFAULTGROUP_ROOT_USERS_DESCRIPTION_0)...K
./....o.................{........ ...............ORA-01403:
no data found
.l.........^...h......
.....................
.................-.......g...............g....Projectmanagers./................
....$longnumber.Projectmanagers3%(key.GUI_DEFAULTGROUP_ROOT_PROJMANS_DESCRIPTION_0)..]../....o.................{........
...............ORA-01403: no data found
...........^...)....w..

I am at a loss on this one, I figured a 7.0.1 to 7.0.3
upgrade would be snap, this is day 3 of troubleshooting for
me. If I can get the sql calls, I can pass this info along
to the DBA here to take a look. Any help would be greatly
appreciated.