14 Replies Latest reply on Jul 5, 2012 10:29 AM by sumantasg

    Server starts in 27 minutes

    sumantasg

      Hi,

       

      I'm using Jboss 4.2.3 to deploy my application.

       

      There is no error in the log but the server is taking about 27 minutes to start. Can someone take a look at the attached log and see if you can help.

       

      Pls mention if you need any other info.

       

      Thanks,

      Sumanta

       

      Not able to upload the log file. trying..

        • 1. Re: Server starts in 27 minutes
          wolfgangknauf

          Did you zip file log file ;-)? Or reduce the size. According to the info label besides the "attach file" button, it is 15 MB.

           

          Does your server have enough memory?

           

          How do you start the server? I found several posts where someone had this problem when starting the server in debug mode using Eclipse: http://community.jboss.org/thread/62582

           

          Best regards

           

          Wolfgang

          • 2. Re: Server starts in 27 minutes
            sumantasg

            Able to upload the log file

             

            I'm not using Eclipse to start server. Its using run.bat from command prompt.

             

            Xms and Xmx are set to 2.5 GB on a 3GB m/c.

             

            ~Sumanta

            • 3. Re: Server starts in 27 minutes
              wolfgangknauf

              I still don't see the log file!? Did you try to make smaller packages?

              • 4. Re: Server starts in 27 minutes
                sumantasg

                here is the portion of the log which may be of interest. After timestamp 2011-07-13 17:47:27,155 , the server prints those debug messages... "IdleRemover notifying pools, interval: 30000". Eventually, the server starts after a while.

                 

                2011-07-13 17:47:00,623 INFO  [nucleusNamespace.atg.registry.PipelineRegistry] Starting Pipeline Registry.

                2011-07-13 17:47:01,076 INFO  [nucleusNamespace.atg.sitemap.SitemapRepository] SQL Repository startup complete

                2011-07-13 17:47:17,045 INFO  [nucleusNamespace.atg.search.repository.IncrementalItemQueueRepository] SQL Repository startup complete

                2011-07-13 17:47:18,608 INFO  [nucleusNamespace.atg.reporting.md.DatasetDomain] Domain service started

                2011-07-13 17:47:18,717 INFO  [nucleusNamespace.atg.reporting.ChartTemplateScheduler] DSS Template scheduler started

                2011-07-13 17:47:19,608 INFO  [nucleusNamespace.atg.commerce.catalog.CatalogChangesListener] Resolving reference to /atg/commerce/catalog/custom/CatalogChangesListener

                2011-07-13 17:47:20,123 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:20,170 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:20,217 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:20,264 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:20,311 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:20,358 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:20,405 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:20,452 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:20,498 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:20,545 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:20,592 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:20,639 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:20,686 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:20,733 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:20,827 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:20,873 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:20,920 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:20,967 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:21,014 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:21,061 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:21,108 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:21,155 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:21,202 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:21,248 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:21,295 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:21,342 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:21,389 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:21,436 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:21,514 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:21,561 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:21,608 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:21,655 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:21,702 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:21,748 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:21,795 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:21,842 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:21,889 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:21,936 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:21,983 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:22,030 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:22,077 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:22,123 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:22,202 INFO  [nucleusNamespace.atg.commerce.search.config.CatalogRankConfigAdapter] Resolving reference to LocalCatalogRankConfigAdapter

                2011-07-13 17:47:22,483 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:22,530 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:22,577 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:22,623 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:22,686 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:22,717 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:22,764 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:22,811 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:22,858 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:22,920 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:22,952 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:22,998 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:23,045 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:23,092 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:23,342 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:23,389 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:23,436 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:23,483 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:23,530 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:23,577 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:23,623 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:23,670 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:23,717 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:23,764 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:23,811 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:23,858 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:23,905 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:23,952 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:24,045 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:24,092 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:24,139 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:24,186 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:24,233 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:24,280 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:24,327 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:24,373 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:24,420 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:24,467 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:24,514 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:24,561 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:24,608 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:24,655 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:24,748 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:24,795 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:24,842 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:24,889 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:24,936 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:24,983 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:25,030 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:25,077 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:25,123 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:25,170 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:25,217 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:25,264 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:47:25,264 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:25,311 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:25,358 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:25,452 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:25,498 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:25,545 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:25,592 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:25,639 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:25,686 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:25,733 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:25,780 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:25,827 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:25,873 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:25,920 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:25,967 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:26,014 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:26,061 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:26,514 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: catalog

                2011-07-13 17:47:26,577 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: productFeature

                2011-07-13 17:47:26,623 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartCategory

                2011-07-13 17:47:26,670 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: award

                2011-07-13 17:47:26,717 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku

                2011-07-13 17:47:26,764 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: sku-link

                2011-07-13 17:47:26,811 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: technicalSpec

                2011-07-13 17:47:26,858 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: product

                2011-07-13 17:47:26,905 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: category

                2011-07-13 17:47:26,952 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: smartSolutionAuthor

                2011-07-13 17:47:26,998 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: specvalue

                2011-07-13 17:47:27,045 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurationOption

                2011-07-13 17:47:27,108 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: configurableProperty

                2011-07-13 17:47:27,155 INFO  [nucleusNamespace.atg.search.repository.IncrementalLoader] Already monitoring: mktFeature

                2011-07-13 17:47:55,264 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:48:25,296 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:48:55,327 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:49:25,327 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:49:55,374 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:50:25,406 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:50:55,422 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:51:25,438 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:51:55,485 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:52:25,500 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:52:55,516 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:53:25,548 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:53:55,563 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:54:25,595 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:54:55,626 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:55:25,658 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:55:55,674 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:56:41,315 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:57:12,205 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:57:42,206 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:58:12,237 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:58:42,253 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:59:12,253 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 17:59:42,284 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 18:00:12,316 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 18:00:42,332 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 18:01:12,363 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                2011-07-13 18:01:42,395 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 30000

                • 5. Re: Server starts in 27 minutes
                  wolfgangknauf

                  How is CPU and memory usage in this idle period? Is the java process doing some work, or is it waiting for something? What is this "nucleusNamespace.atg.search.repository.IncrementalLoader"? Some external monitoring component?

                  • 6. Re: Server starts in 27 minutes
                    sumantasg

                    I'm able to reduce the server start time by increasing Xms and Xmx  in JAVA_OPTS

                     

                    with 2GB, it takes about 35 minutes to start.

                    with 2.5GB, it takes about 27 minutes to start.

                     

                    Java memory usage does not change during this time.

                    • 7. Re: Server starts in 27 minutes
                      mikefinn

                      Still a crazy amount of time to start. Maybe you should try undeploying anything that didn't come out of box and see what your base start time is.

                       

                      Mike

                      • 8. Re: Server starts in 27 minutes
                        sumantasg

                        guess what..  with 3.5 GB RAM it takes about 18 minutes to start! I'm helpless with further increase in JVM memory as I've only 4GB!

                         

                        with the removal of initial scheduled services, it does not help much! about 30 sec saving in start time.

                         

                        Mike, its difficult to undeply selectively as all the packages are related to each other. The integrity will be broken.

                         

                        I'm using JBoss-4.2.3.GA which ATG recommended that I should use at least JBoss-4.3.0.  May this be causing the delay ?

                         

                        I didn't get JBoss-4.3.0.GA version on the Jboss site.

                         

                        Sumanta

                        • 9. Re: Server starts in 27 minutes
                          wolfgangknauf

                          Hi,

                           

                          you might try to create a Thread dump: http://community.jboss.org/wiki/ThreadDump

                          If you do this several times in the long "nothing happens" phase, you might identiy the bottleneck.

                           

                          JBoss 4.3.0 is the EAP (commercial) version as far as I know. I am not sure about it, but I think it is based on JBoss 5.0 internally. So, you might try it with JBoss 5.1.0 or even 6.0. But I fear that migration to it might be a bit of a problem, because the 5.0 versions are much stricter on validation of xml deployment descriptors, and there is a big chance for library conflicts.

                           

                          Best regards

                           

                          Wolfgang

                          • 10. Re: Server starts in 27 minutes
                            jaikiran

                            Wolfgang Knauf wrote:

                             

                            JBoss 4.3.0 is the EAP (commercial) version as far as I know.

                            That's correct.

                             

                             

                            Wolfgang Knauf wrote:

                             

                            I am not sure about it, but I think it is based on JBoss 5.0 internally. So, you might try it with JBoss 5.1.0 or even 6.0. But I fear that migration to it might be a bit of a problem, because the 5.0 versions are much stricter on validation of xml deployment descriptors, and there is a big chance for library conflicts.

                             

                            Actually, JBoss EAP 4.3 is based on JBoss AS 4.2.1 http://www.jboss.com/products/platforms/application/components/#JEAP4.3

                            • 11. Re: Server starts in 27 minutes
                              wolfgangknauf

                              Oops, sorry for providing wrong information :-(

                              • 12. Re: Server starts in 27 minutes
                                sumantasg

                                Thread dump results are as follows. There's a lot of those.

                                 

                                Full thread dump Java HotSpot(TM) 64-Bit Server VM (20.1-b02 mixed mode):

                                 

                                 

                                "/atg/reporting/datacollection/search/EnvironmentLogEntryQueueSink-0" prio=6 tid

                                =0x0000000015bc5800 nid=0x218 in Object.wait() [0x000000001eaef000]

                                   java.lang.Thread.State: WAITING (on object monitor)

                                        at java.lang.Object.wait(Native Method)

                                        - waiting on <0x00000007698aae80> (a atg.service.datacollection.DataColl

                                ectorQueue)

                                        at java.lang.Object.wait(Object.java:485)

                                        at atg.service.queue.EventQueue.getElement(EventQueue.java:214)

                                        - locked <0x00000007698aae80> (a atg.service.datacollection.DataCollecto

                                rQueue)

                                        at atg.service.queue.EventQueue.dispatchQueueElements(EventQueue.java:26

                                3)

                                        at atg.service.queue.EventQueue$Handler.run(EventQueue.java:69)

                                 

                                 

                                "/atg/dynamo/service/Scheduler-reusablejobhandler-/atg/epub/file/ConfigEmptyDirD

                                eleter-folderCleanup" prio=6 tid=0x0000000015bc5000 nid=0xbc0 in Object.wait() [

                                0x000000001e9ef000]

                                   java.lang.Thread.State: WAITING (on object monitor)

                                        at java.lang.Object.wait(Native Method)

                                        - waiting on <0x000000076aadaa10> (a atg.service.scheduler.ScheduledJob)

                                 

                                 

                                        at java.lang.Object.wait(Object.java:485)

                                        at atg.service.scheduler.ScheduledJob.waitForSignal(ScheduledJob.java:48

                                8)

                                        - locked <0x000000076aadaa10> (a atg.service.scheduler.ScheduledJob)

                                        at atg.service.scheduler.ScheduledJob.runJobs(ScheduledJob.java:421)

                                        at atg.service.scheduler.Scheduler$2handler.run(Scheduler.java:760)

                                 

                                 

                                "/atg/dynamo/service/Scheduler-reusablejobhandler-PlaceScheduledOrders" prio=6 t

                                id=0x0000000015bc4000 nid=0x188 in Object.wait() [0x000000001e8ef000]

                                   java.lang.Thread.State: WAITING (on object monitor)

                                        at java.lang.Object.wait(Native Method)

                                        - waiting on <0x000000076aadb330> (a atg.service.scheduler.ScheduledJob)

                                 

                                 

                                        at java.lang.Object.wait(Object.java:485)

                                        at atg.service.scheduler.ScheduledJob.waitForSignal(ScheduledJob.java:48

                                8)

                                        - locked <0x000000076aadb330> (a atg.service.scheduler.ScheduledJob)

                                        at atg.service.scheduler.ScheduledJob.runJobs(ScheduledJob.java:421)

                                        at atg.service.scheduler.Scheduler$2handler.run(Scheduler.java:760)

                                • 13. Re: Server starts in 27 minutes
                                  wolfgangknauf

                                  I don't know whether this is the expected behaviour, but as you wrote that there are a lot of threads in "Object.wait" state, you might contact the ATG guys. Maybe their scheduler got stuck. Even if this is a JBoss problem in the end, they hopefully have more detail knowledge to help you further.

                                   

                                  Are those those ATG jobs configured by your app? Maybe there is something you could try to change.

                                   

                                  Best regards

                                   

                                  Wolfgang

                                  • 14. Re: Server starts in 27 minutes
                                    sumantasg

                                    Although this was sorted out long back, I thought of posting this.

                                     

                                    Aparently it was nothing to do with JBOSS. Our search environments were creating this issue. My database was a production copy and it had about 14 search environments. I deleted them and created new ones and the issue was resolved. After all these, server start time was reduced to 2.5 minues.