Details
-
Bug
-
Resolution: Done
-
Blocker
-
2.5.0.Final
Description
I have SOA-P 5.2 ER1 build with Modeshape installed and configured to use PostgreSQL 8.4 as backend database. First start-up of the server - with clean database schema - went quick, it took approximately 2 minutes. Then I shutdown server and started it again. During the startup, server hung at these lines:
17:08:30,400 INFO [SchemaUpdate] Running hbm2ddl schema update 17:08:30,400 INFO [SchemaUpdate] fetching database metadata 17:08:30,401 INFO [SchemaUpdate] updating schema 17:08:30,441 INFO [TableMetadata] table found: public.dna_changelog 17:08:30,441 INFO [TableMetadata] columns: [id, username, change_count, utc_timestamp, changes] 17:08:30,441 INFO [TableMetadata] foreign keys: [] 17:08:30,441 INFO [TableMetadata] indexes: [dna_changelog_pkey, ns_change_ts_inx] 17:08:30,464 INFO [TableMetadata] table found: public.dna_namespaces 17:08:30,464 INFO [TableMetadata] columns: [id, uri] 17:08:30,464 INFO [TableMetadata] foreign keys: [] 17:08:30,464 INFO [TableMetadata] indexes: [ns_uri_inx, dna_namespaces_pkey] 17:08:30,486 INFO [TableMetadata] table found: public.dna_workspaces 17:08:30,486 INFO [TableMetadata] columns: [id, name] 17:08:30,486 INFO [TableMetadata] foreign keys: [] 17:08:30,486 INFO [TableMetadata] indexes: [ws_name_inx, dna_workspaces_pkey] 17:08:30,510 INFO [TableMetadata] table found: public.mode_options 17:08:30,510 INFO [TableMetadata] columns: [name, value] 17:08:30,510 INFO [TableMetadata] foreign keys: [] 17:08:30,510 INFO [TableMetadata] indexes: [mode_options_pkey] 17:08:30,533 INFO [TableMetadata] table found: public.mode_simple_large_values 17:08:30,533 INFO [TableMetadata] columns: [compressed, length, data, sha1, type] 17:08:30,533 INFO [TableMetadata] foreign keys: [] 17:08:30,533 INFO [TableMetadata] indexes: [mode_simple_large_values_pkey] 17:08:30,562 INFO [TableMetadata] table found: public.mode_simple_node 17:08:30,562 INFO [TableMetadata] columns: [child_name_local, child_name_ns_id, node_uuid, data, workspace_id, allows_sns, id, compressed, child_index, num_props, sns_index, enforcerefinteg, parent_id] 17:08:30,562 INFO [TableMetadata] foreign keys: [fke43e2bd346ef783f, fke43e2bd360f39011] 17:08:30,562 INFO [TableMetadata] indexes: [mode_simple_node_pkey, nodeuuid_inx, childindex_inx, childname_inx] 17:08:30,588 INFO [TableMetadata] table found: public.mode_subgraph_nodes 17:08:30,588 INFO [TableMetadata] columns: [id, child_num, parent_num, uuid, query_id, depth] 17:08:30,588 INFO [TableMetadata] foreign keys: [] 17:08:30,588 INFO [TableMetadata] indexes: [queryid_inx, mode_subgraph_nodes_pkey] 17:08:30,615 INFO [TableMetadata] table found: public.mode_subgraph_queries 17:08:30,615 INFO [TableMetadata] columns: [id, workspace_id, root_uuid] 17:08:30,615 INFO [TableMetadata] foreign keys: [] 17:08:30,615 INFO [TableMetadata] indexes: [mode_subgraph_queries_pkey] 17:08:30,640 INFO [TableMetadata] table found: public.modeshape_largevalue_usages 17:08:30,640 INFO [TableMetadata] columns: [id, largevalues_sha1] 17:08:30,640 INFO [TableMetadata] foreign keys: [fk4b54e5da1b6fbc1c, fk4b54e5dae58cec5c] 17:08:30,640 INFO [TableMetadata] indexes: [modeshape_largevalue_usages_pkey] 17:08:30,641 INFO [SchemaUpdate] schema update complete
Then server stayed here for 5 or 6 minutes and then continued in startup:
17:14:45,804 INFO [ModeShapeEngine] Completed starting the "eds" repository 17:14:45,845 INFO [JNDIManagedRepositories] ModeShape Repositories bound to JNDI at name "jcr/local".
This happened also in third startup attempt. So, first start with clean DB schema went OK - it took usual starting time - no hanging.
Second, third and every other startup hung on "schema update complete" line, stayed there 5 minutes and then continued. Schema update with production DB shouldn't take such amount of time.