Kuali Rice Development
  1. Kuali Rice Development
  2. KULRICE-12988

KC app startup time really slow with the latest rice 2.5 revision

    Details

    • Type: Task Task
    • Status: Closed Closed
    • Priority: Blocker Blocker
    • Resolution: Fixed
    • Affects Version/s: 2.5
    • Fix Version/s: 2.5
    • Component/s: Data Dictionary
    • Security Level: Public (Public: Anyone can view)
    • Labels:
      None
    • Similar issues:
      KULRICE-12991Application startup time slow after Spring 4.0.x upgrade
      KULRICE-4990fixing log4j errors in rice on tomcat startup
      KULRICE-13070Pages load really slow after rice upgrade
      KULRICE-4070XmlHelper.appendXml(Node, String) can be really slow. (i.e. takes minutes)
      KULRICE-13037Krad Sampleapp startup failing due to npe with max incrementer factory
      KULRICE-531development slowness
      KULRICE-4392Update sample applications to latest version of rice
      KULRICE-13687Sample App Gap Analysis - 2.5.0-m4 QA Sprint 1
      KULRICE-13719Sample App Gap Analysis - QA 2.5.0-m5 QA Sprint 1
      KULRICE-13744Sample App Gap Analysis - QA 2.5.0-m5 QA Sprint 2
    • Rice Module:
      KRAD
    • Application Requirement:
      KC
    • Sprint:
      Core 2.5.0-m5 Sprint 2
    • KAI Review Status:
      Not Required
    • KTI Review Status:
      Not Required
    • Code Review Status:
      Not Required
    • Include in Release Notes?:
      Yes

      Description

      While upgrading to Rice 2.5.0-M5-r47560, we are noticing really slow start up times. Here is the relevant logs for the issue

      LOG
      2014-07-17 11:26:35,490 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryEntryBase - No metadata provider exists which handles: class org.kuali.kra.irb.actions.submit.ValidProtocolActionAction
      2014-07-17 11:26:35,490 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryEntryBase - No metadata provider exists which handles: class org.kuali.kra.irb.actions.submit.ValidProtoSubRevType
      2014-07-17 11:26:35,490 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryEntryBase - No metadata provider exists which handles: class org.kuali.kra.irb.actions.submit.ValidProtoSubTypeQual
      2014-07-17 11:26:35,490 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryEntryBase - No metadata provider exists which handles: class org.kuali.kra.award.home.ValidRates
      2014-07-17 11:26:35,490 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryEntryBase - No metadata provider exists which handles: class org.kuali.coeus.common.framework.compliance.core.ValidSpecialReviewApproval
      2014-07-17 11:26:35,490 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryEntryBase - No metadata provider exists which handles: class org.kuali.coeus.common.framework.print.watermark.Watermark
      2014-07-17 11:26:35,490 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryIndex - Starting DD Index Building
      2014-07-17 11:26:36,096 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryIndex - Completed DD Index Building
      2014-07-17 11:26:36,096 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryIndex - Started DD Inactivation Blocking Index Building
      2014-07-17 11:26:36,295 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionaryIndex - Completed DD Inactivation Blocking Index Building
      2014-07-17 11:33:50,045 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.uif.UifDictionaryIndex - Starting View Index Building
      2014-07-17 11:33:50,153 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.uif.UifDictionaryIndex - Completed View Index Building
      2014-07-17 11:33:50,153 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.datadictionary.DataDictionary - Completed Data Dictionary Post Processing
      2014-07-17 11:33:50,282 [RMI TCP Connection(2)-127.0.0.1] u:/d: INFO  org.kuali.rice.krad.config.KRADConfigurer - KRAD Configurer - Validating DD
      2014-07-17 11:33:50,542 [RMI TCP Connection(2)-127.0.0.1] u:/d: WARN  org.kuali.rice.krad.data.provider.impl.ProviderBasedDataObjectService - Non KRAD Data object
      

      as you can see, this slow down is right after the

      Completed DD Inactivation Blocking Index Building and it takes close to 7 minutes. I noticed that the code that takes the longest to complete are the following two:

      DataDictionary.java
      generateMissingInquiryDefinitions();
      generateMissingLookupDefinitions();
      

      when I comment these lines out, the app starts up in no time however there are issues with lookups. This code seems to call into some spring methods and I am guessing this slowness is because of the spring 4.x upgrade. I have tried various revisions after the spring 4.x upgrade and it is slow in all of those.

        Issue Links

          Activity

            People

            • Assignee:
              Kristina Taylor (Inactive)
              Reporter:
              Gayathri Athreya
            • Votes:
              0 Vote for this issue
              Watchers:
              4 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved:

                Time Tracking

                Estimated:
                Original Estimate - 4 hours
                4h
                Remaining:
                Remaining Estimate - 0 minutes
                0m
                Logged:
                Time Spent - 4 hours
                4h

                  Agile

                    Structure Helper Panel