Uploaded image for project: 'ModeShape'
  1. ModeShape
  2. MODE-1224

Server starts slow upon subsequent (second, third, etc.) restarts

    Details

    • Type: Bug
    • Status: Closed (View Workflow)
    • Priority: Blocker
    • Resolution: Done
    • Affects Version/s: 2.5.0.Final
    • Fix Version/s: 2.5.1.GA, 2.6.0.Final
    • Component/s: JCR
    • Labels:
    • Environment:

      jdk1.6_22, Fedora 14, Postgresql 8.4

    • Workaround:
      Workaround Exists
    • Workaround Description:
      Hide
      1. generate the DDL for PostgreSQL (or the appropriate DBMS) using ModeShape's DDL generation tool;
      2. manually optimize the schema for the particular installation/configuration of PostgreSQL;
      3. manually set up the PostgreSQL database by running the DDL; and
      4. change the 'autoGenerateSchema' value to 'disable';
      Show
      generate the DDL for PostgreSQL (or the appropriate DBMS) using ModeShape's DDL generation tool; manually optimize the schema for the particular installation/configuration of PostgreSQL; manually set up the PostgreSQL database by running the DDL; and change the 'autoGenerateSchema' value to 'disable';

      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.

        Gliffy Diagrams

          Attachments

          1. modeshape-config.xml
            20 kB
          2. server.log
            45 kB
          3. server.tar.bz2
            230 kB

            Issue Links

              Activity

                People

                • Assignee:
                  rhauch Randall Hauch
                  Reporter:
                  bbelovic Boris Belovic
                • Votes:
                  0 Vote for this issue
                  Watchers:
                  1 Start watching this issue

                  Dates

                  • Created:
                    Updated:
                    Resolved: