Problems starting up Artifactory

classic Classic list List threaded Threaded
2 messages Options
Reply | Threaded
Open this post in threaded view
|

Problems starting up Artifactory

lvim
This post was updated on .
Hi,

We have some problems starting up Artifactory (version 2.3.4.1):

(1) After 4 hours, we get the following message in ARTIDACTORY GUI (port 8080):

Artifactory is starting up...

Please wait while startup completes..........

This page will refresh automatically once Artifactory is ready.


(2) And finally, ARTIFACTORY started after 42131 seconds:

###########################################################
### Artifactory successfully started (42131 seconds)    ###
###########################################################



The output of "consoleout.log" file is:

2015-08-04 16:10:13,242 [art-init] [INFO ] (o.a.w.s.ArtifactoryContextConfigListener:127) -
               _   _  __           _
    /\        | | (_)/ _|         | |
   /  \   _ __| |_ _| |_ __ _  ___| |_ ___  _ __ _   _
  / /\ \ | '__| __| |  _/ _` |/ __| __/ _ \| '__| | | |
 / ____ \| |  | |_| | || (_| | (__| || (_) | |  | |_| |
/_/    \_\_|   \__|_|_| \__,_|\___|\__\___/|_|   \__, |
 Version:  2.3.4.1                                __/ |
 Revision: 13021                                 |___/
...

2015-08-04 16:10:13,546 [art-init] [INFO ] (o.a.s.SpringConfigResourceLoader:100) - artifactory.addons.disabled=[]
2015-08-04 16:10:13,617 [art-init] [DEBUG] (o.a.s.SpringConfigPaths:60) - Spring configuration paths: /META-INF/spring/addons.xml
2015-08-04 16:10:13.673:INFO::Opened ###INFO_HIDDEN###appl/artifactory-2.3.4.1/logs/2015_08_04.request.log
2015-08-04 16:10:13,676 [art-init] [INFO ] (o.a.s.ArtifactoryApplicationContext:200) - Refreshing org.artifactory.spring.ArtifactoryApplicationContext@913dc1: startup date [Tue Aug 04 16:10:
13 CEST 2015]; root of context hierarchy
2015-08-04 16:10:13.737:INFO::Started SelectChannelConnector@0.0.0.0:8080
...
2015-08-04 16:10:41,223 [art-init] [DEBUG] (o.a.j.c.v.AbstractVISProvider:370) - item added to cache. size=200
2015-08-04 16:10:41,433 [art-init] [DEBUG] (o.a.j.c.RepositoryImpl:2127) - initializing SearchManager...
2015-08-04 16:10:43,096 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:354) - Found rule 'org.apache.jackrabbit.core.query.lucene.IndexingConfigurationImpl$IndexingRule@9e53ed' for NodeType '{http://artifactory.jfrog.org/1.0}file'
2015-08-04 16:10:43,098 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}file'
2015-08-04 16:10:43,101 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:354) - Found rule 'org.apache.jackrabbit.core.query.lucene.IndexingConfigurationImpl$IndexingRule@5084c6' for NodeType '{http://artifactory.jfrog.org/1.0}folder'
2015-08-04 16:10:43,103 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}folder'
2015-08-04 16:10:43,120 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:354) - Found rule 'org.apache.jackrabbit.core.query.lucene.IndexingConfigurationImpl$IndexingRule@12a6e85' for NodeType '{http://artifactory.jfrog.org/1.0}metadata'
2015-08-04 16:10:43,122 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}metadata'
2015-08-04 16:10:43,124 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:354) - Found rule 'org.apache.jackrabbit.core.query.lucene.IndexingConfigurationImpl$IndexingRule@bdfa0e' for NodeType '{http://artifactory.jfrog.org/1.0}logEntry'
2015-08-04 16:10:43,126 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}logEntry'
2015-08-04 16:10:43,130 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:354) - Found rule 'org.apache.jackrabbit.core.query.lucene.IndexingConfigurationImpl$IndexingRule@6bcf5d' for NodeType '{http://www.jcp.org/jcr/nt/1.0}unstructured'
2015-08-04 16:10:43,131 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}file'
2015-08-04 16:10:43,132 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{internal}root'
2015-08-04 16:10:43,133 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}folder'
2015-08-04 16:10:43,134 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://www.jcp.org/jcr/nt/1.0}unstructured'
2015-08-04 16:10:43,136 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}logEntry'
2015-08-04 16:10:43,137 [art-init] [DEBUG] (o.a.j.c.q.l.IndexingConfigurationImpl:362) - Registering it for name '{http://artifactory.jfrog.org/1.0}metadata'
2015-08-04 16:10:44,238 [art-init] [DEBUG] (o.a.j.c.q.l.IndexMigration:75) - Checking org.apache.jackrabbit.core.query.lucene.directory.FSDirectoryManager$FSDir@org.apache.lucene.store.FSDirectory@###INFO_HIDDEN###/artifactory/artifactory.data/data/index/_a ...
2015-08-04 16:10:44,595 [art-init] [DEBUG] (o.a.j.c.q.l.IndexMigration:82) - IndexFormatVersion >= V3, no migration needed
2015-08-04 16:10:44,661 [art-init] [DEBUG] (o.a.j.c.q.l.IndexMerger:175) - index added: name=_a, numDocs=15623
2015-08-04 16:10:44,815 [art-init] [DEBUG] (o.a.j.c.q.l.IndexMigration:75) - Checking org.apache.jackrabbit.core.query.lucene.directory.FSDirectoryManager$FSDir@org.apache.lucene.store.FSDirectory@###INFO_HIDDEN###/artifactory/artifactory.data/data/index/_l ...
2015-08-04 16:10:44,959 [art-init] [DEBUG] (o.a.j.c.q.l.IndexMigration:82) - IndexFormatVersion >= V3, no migration needed
2015-08-04 16:10:45,033 [art-init] [DEBUG] (o.a.j.c.q.l.IndexMerger:175) - index added: name=_l, numDocs=10497
2015-08-04 16:10:45,234 [art-init] [DEBUG] (o.a.j.c.q.l.IndexMigration:75) - Checking org.apache.jackrabbit.core.query.lucene.directory.FSDirectoryManager$FSDir@org.apache.lucene.store.FSDirectory@###INFO_HIDDEN###/artifactory/artifactory.data/data/index/_w ...
...
2015-08-04 20:19:07,498 [art-init] [DEBUG] (o.a.j.c.u.d.ConnectionHelper:468) - Executing SQL: 'select BUNDLE_DATA from DEFAULT_BUNDLE where NODE_ID_HI = ? and NODE_ID_LO = ?' with params: '
-5698211641956940325','-7924177755954890775'.
...
2015-08-05 03:52:24,627 [art-init] [DEBUG] (o.a.j.c.s.SessionState:185) - Performing node.getProperty(groups)
2015-08-05 03:52:24,628 [art-init] [DEBUG] (o.a.j.c.s.SessionState:195) - Performed node.getProperty(groups) in 34us
2015-08-05 03:52:24,628 [art-init] [DEBUG] (o.a.j.JcrTransactionManager:118) - Saving session: org.artifactory.jcr.JcrSession@14882.
2015-08-05 03:52:24,629 [art-init] [DEBUG] (o.a.j.JcrSession    :188) - saving session
2015-08-05 03:52:24,629 [art-init] [DEBUG] (o.a.j.c.s.SessionState:185) - Performing session.save()
2015-08-05 03:52:24,630 [art-init] [DEBUG] (o.a.j.c.ItemManager :895) - caching item cafebabe-cafe-babe-cafe-babecafebabe
2015-08-05 03:52:24,630 [art-init] [DEBUG] (o.a.j.c.s.SessionState:185) - Performing item.save()
2015-08-05 03:52:24,630 [art-init] [DEBUG] (o.a.j.c.s.SessionState:195) - Performed item.save() in 28us
2015-08-05 03:52:24,631 [art-init] [DEBUG] (o.a.j.c.s.SessionState:192) - Performed session.save() in 1ms
2015-08-05 03:52:24,631 [art-init] [DEBUG] (o.a.j.JcrTransactionManager:752) - Initiating transaction commit
2015-08-05 03:52:24,633 [art-init] [DEBUG] (o.a.s.ArtifactoryApplicationContext:238) - Initialized org.artifactory.security.InternalSecurityService
2015-08-05 03:52:24,634 [art-init] [DEBUG] (o.a.s.ArtifactoryApplicationContext:335) - Signaling context ready=true to context readiness listener beans.
2015-08-05 03:52:24,635 [art-init] [DEBUG] (o.a.s.ArtifactoryApplicationContext:338) - Signaling context ready=true to org.artifactory.schedule.TaskService.
2015-08-05 03:52:24,672 [art-init] [INFO ] (o.a.s.ArtifactoryApplicationContext:347) - Artifactory application context is ready.
2015-08-05 03:52:24,683 [art-init] [INFO ] (o.a.w.s.ArtifactoryContextConfigListener:182) -
###########################################################
### Artifactory successfully started (42131 seconds)    ###
###########################################################


We updated the ARTIFACTORY trace level to see DEBUG traces.

What's happening in ARTIFACTORY?

Thanks.
Reply | Threaded
Open this post in threaded view
|

Re: Problems starting up Artifactory

denebeim
It looks to me like it got hung on a database query.  What database are you running?  How many artifacts are you storing in your artifactory?  If it's small, you might consider exporting the filestore, wiping the database, and reimporting it.  That may be easier than trying to figure out what's wrong with your database.

Also, have you considered running a version that's less than 4 years old?