Skip to content

Instantly share code, notes, and snippets.

@alhambrav

alhambrav/gist:3965283299a31d8dd4aef4962f58f50a

Created January 21, 2020 08:32
Show Gist options
  • Save alhambrav/3965283299a31d8dd4aef4962f58f50a to your computer and use it in GitHub Desktop.
Save alhambrav/3965283299a31d8dd4aef4962f58f50a to your computer and use it in GitHub Desktop.
Download ZIP
Error creating first editorial site after fresh install of Studio
Raw
gistfile1.txt
[INFO] 2020-01-21T03:24:22,475 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Starting site creation process for site foobar from org.craftercms.blueprint.editorial blueprint.
[INFO] 2020-01-21T03:24:22,476 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Creating deployer targets.
[INFO] 2020-01-21T03:24:26,362 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Copying site content from blueprint.
[WARN] 2020-01-21T03:24:29,184 [http-nio-8080-exec-2] [lib.Repository] | close() called when useCnt is already zero for Repository[/Users/vita.alhambra/temp/test3/craftercms/crafter-authoring/data/repos/sites/foobar/published/.git]
[INFO] 2020-01-21T03:24:29,319 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Adding site record to database for site foobar
[INFO] 2020-01-21T03:24:29,321 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Upgrading site.
[INFO] 2020-01-21T03:24:29,322 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for site foobar
[INFO] 2020-01-21T03:24:29,410 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for configuration in site foobar
[INFO] 2020-01-21T03:24:29,417 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/site-config.xml
[INFO] 2020-01-21T03:24:29,435 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/permission-mappings-config.xml
[INFO] 2020-01-21T03:24:29,453 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/role-mappings-config.xml
[INFO] 2020-01-21T03:24:29,468 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/administration/config-list.xml
[INFO] 2020-01-21T03:24:29,486 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/administration/site-config-tools.xml
[INFO] 2020-01-21T03:24:29,501 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/environment/environment-config.xml
[INFO] 2020-01-21T03:24:29,515 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/context-nav/contextual-nav.xml
[INFO] 2020-01-21T03:24:29,529 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/aws/aws.xml
[INFO] 2020-01-21T03:24:29,531 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/box/box.xml
[INFO] 2020-01-21T03:24:29,533 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/webdav/webdav.xml
[INFO] 2020-01-21T03:24:29,535 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/workflow/notification-config.xml
[INFO] 2020-01-21T03:24:29,552 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/studio/code-editor-config.xml
[INFO] 2020-01-21T03:24:29,566 [http-nio-8080-exec-2] [upgrade.DefaultUpgradeManagerImpl] | Checking upgrades for file /config/engine/site-config.xml
[INFO] 2020-01-21T03:24:29,584 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Adding default groups
[WARN] 2020-01-21T03:24:29,586 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Default group: site_author not created. It already exists.
[WARN] 2020-01-21T03:24:29,587 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Default group: site_publisher not created. It already exists.
[WARN] 2020-01-21T03:24:29,588 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Default group: site_developer not created. It already exists.
[WARN] 2020-01-21T03:24:29,590 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Default group: site_reviewer not created. It already exists.
[WARN] 2020-01-21T03:24:29,590 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Default group: site_admin not created. It already exists.
[INFO] 2020-01-21T03:24:29,591 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Reload site configuration
[INFO] 2020-01-21T03:24:29,731 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Syncing database with repository.
[INFO] 2020-01-21T03:24:30,045 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Syncing database with repository for site: foobar fromCommitId = 1f9bacf55242293abcf5675c4299888cf876425f
[INFO] 2020-01-21T03:24:36,385 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Done syncing database with repository for site: foobar fromCommitId = 1f9bacf55242293abcf5675c4299888cf876425f with a final result of: true
[INFO] 2020-01-21T03:24:36,385 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Last commit ID for site: foobar is e7f843048d57eb78756361882c2da018410d3a78
[INFO] 2020-01-21T03:24:36,385 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Performing initial deployment
[INFO] 2020-01-21T03:24:36,674 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Syncing all content to preview.
[INFO] 2020-01-21T03:24:40,388 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[INFO] 2020-01-21T03:24:40,389 [http-nio-8080-exec-10] [] [context.SiteContextManager] | <Creating site context: foobar>
[INFO] 2020-01-21T03:24:40,390 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[INFO] 2020-01-21T03:24:41,065 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:41,065 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | <Loading configuration for site: foobar>
[INFO] 2020-01-21T03:24:41,065 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:41,075 [http-nio-8080-exec-10] [] [config.MultiResourceConfigurationBuilder] | Loading XML configurations in the order in which the properties will be resolved
[INFO] 2020-01-21T03:24:41,709 [http-nio-8080-exec-10] [] [beanutils.FluentPropertyBeanIntrospector] | Error when creating PropertyDescriptor for public final void org.apache.commons.configuration2.AbstractConfiguration.setProperty(java.lang.String,java.lang.Object)! Ignoring this property.
[INFO] 2020-01-21T03:24:41,867 [http-nio-8080-exec-10] [] [config.MultiResourceConfigurationBuilder] | XML configuration loaded from foobar:/config/engine/site-config.xml
[INFO] 2020-01-21T03:24:41,867 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:41,867 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | </Loading configuration for site: foobar>
[INFO] 2020-01-21T03:24:41,867 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:41,930 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:41,931 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | <Loading application context for site: foobar>
[INFO] 2020-01-21T03:24:41,931 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:41,950 [http-nio-8080-exec-10] [] [xml.XmlBeanDefinitionReader] | Loading XML bean definitions from foobar:/config/engine/application-context.xml
[INFO] 2020-01-21T03:24:41,981 [http-nio-8080-exec-10] [] [support.GenericApplicationContext] | Refreshing org.springframework.context.support.GenericApplicationContext@1255e858: startup date [Tue Jan 21 03:24:41 EST 2020]; parent: Root WebApplicationContext
[INFO] 2020-01-21T03:24:41,999 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,000 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | </Loading application context for site: foobar>
[INFO] 2020-01-21T03:24:42,000 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,000 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,002 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | <Loading URL rewrite engine for site: foobar>
[INFO] 2020-01-21T03:24:42,002 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,005 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,005 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | </Loading URL rewrite engine for site: foobar>
[INFO] 2020-01-21T03:24:42,005 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,006 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,006 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | <Scheduling job scripts for site: foobar>
[INFO] 2020-01-21T03:24:42,006 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,012 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,012 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | </Scheduling job scripts for site: foobar>
[INFO] 2020-01-21T03:24:42,013 [http-nio-8080-exec-10] [] [context.SiteContextFactory] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,014 [http-nio-8080-exec-10] [] [context.SiteContextManager] | Site context created: SiteContext{siteName='foobar', context=FileSystemContext{id='acea8714842c2cabfd54b9ff52abdb8b', rootFolderPath='file:/Users/vita.alhambra/temp/test3/craftercms/crafter-authoring/data/repos/sites/foobar/sandbox/'}, fallback=false, staticAssetsPath='/static-assets', templatesPath='/', restScriptsPath='/scripts/rest', controllerScriptsPath='/scripts/controllers'}
[INFO] 2020-01-21T03:24:42,015 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[INFO] 2020-01-21T03:24:42,015 [http-nio-8080-exec-10] [] [context.SiteContextManager] | </Creating site context: foobar>
[INFO] 2020-01-21T03:24:42,015 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[INFO] 2020-01-21T03:24:42,026 [pool-15-thread-1] [foobar] [context.SiteContext] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,027 [pool-15-thread-1] [foobar] [context.SiteContext] | <Initializing context site: foobar>
[INFO] 2020-01-21T03:24:42,027 [pool-15-thread-1] [foobar] [context.SiteContext] | --------------------------------------------------
[INFO] 2020-01-21T03:24:42,027 [pool-15-thread-1] [foobar] [context.SiteContext] | Starting GraphQL schema build for site 'foobar'
[INFO] 2020-01-21T03:24:42,327 [http-nio-8080-exec-2] [site.SiteServiceImpl] | Finished creating site foobar
[ERROR] 2020-01-21T03:24:43,514 [http-nio-8080-exec-10] [] [context.SiteContextManager] | Site context SiteContext{siteName='foobar', context=FileSystemContext{id='acea8714842c2cabfd54b9ff52abdb8b', rootFolderPath='file:/Users/vita.alhambra/temp/test3/craftercms/crafter-authoring/data/repos/sites/foobar/sandbox/'}, fallback=false, staticAssetsPath='/static-assets', templatesPath='/', restScriptsPath='/scripts/rest', controllerScriptsPath='/scripts/controllers'} is not valid anymore
[INFO] 2020-01-21T03:24:43,515 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[INFO] 2020-01-21T03:24:43,515 [http-nio-8080-exec-10] [] [context.SiteContextManager] | <Destroying site context: foobar>
[INFO] 2020-01-21T03:24:43,515 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[INFO] 2020-01-21T03:24:43,515 [http-nio-8080-exec-10] [] [support.GenericApplicationContext] | Closing org.springframework.context.support.GenericApplicationContext@1255e858: startup date [Tue Jan 21 03:24:41 EST 2020]; parent: Root WebApplicationContext
[INFO] 2020-01-21T03:24:43,516 [http-nio-8080-exec-10] [] [context.SiteContextManager] | Site context destroyed: SiteContext{siteName='foobar', context=FileSystemContext{id='acea8714842c2cabfd54b9ff52abdb8b', rootFolderPath='file:/Users/vita.alhambra/temp/test3/craftercms/crafter-authoring/data/repos/sites/foobar/sandbox/'}, fallback=false, staticAssetsPath='/static-assets', templatesPath='/', restScriptsPath='/scripts/rest', controllerScriptsPath='/scripts/controllers'}
[INFO] 2020-01-21T03:24:43,516 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[INFO] 2020-01-21T03:24:43,517 [http-nio-8080-exec-10] [] [context.SiteContextManager] | </Destroying site context: foobar>
[INFO] 2020-01-21T03:24:43,517 [http-nio-8080-exec-10] [] [context.SiteContextManager] | ==================================================
[ERROR] 2020-01-21T03:24:43,517 [http-nio-8080-exec-10] [] [filter.SiteContextResolvingFilter] | Error while resolving site context for current request
java.lang.IllegalStateException: Unable to resolve context for site name 'foobar'
at org.craftercms.engine.service.context.SiteContextResolverImpl.getContext(SiteContextResolverImpl.java:81) ~[classes/:3.1.5-SNAPSHOT]
at org.craftercms.engine.servlet.filter.SiteContextResolvingFilter.getContext(SiteContextResolvingFilter.java:95) [classes/:3.1.5-SNAPSHOT]
at org.craftercms.engine.servlet.filter.SiteContextResolvingFilter.doFilter(SiteContextResolvingFilter.java:79) [classes/:3.1.5-SNAPSHOT]
at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347) [spring-web-4.3.18.RELEASE.jar:4.3.18.RELEASE]
at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263) [spring-web-4.3.18.RELEASE.jar:4.3.18.RELEASE]
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.24]
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.24]
at org.craftercms.commons.http.RequestContextBindingFilter.doFilter(RequestContextBindingFilter.java:79) [crafter-commons-utilities-3.1.5-SNAPSHOT.jar:3.1.5-SNAPSHOT]
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.24]
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.24]
at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:197) [spring-web-4.3.18.RELEASE.jar:4.3.18.RELEASE]
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) [spring-web-4.3.18.RELEASE.jar:4.3.18.RELEASE]
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.24]
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.24]
at org.apache.logging.log4j.web.Log4jServletFilter.doFilter(Log4jServletFilter.java:71) [log4j-web-2.11.2.jar:2.11.2]
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.24]
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.24]
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:198) [catalina.jar:8.5.24]
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96) [catalina.jar:8.5.24]
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) [catalina.jar:8.5.24]
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140) [catalina.jar:8.5.24]
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81) [catalina.jar:8.5.24]
at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:650) [catalina.jar:8.5.24]
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87) [catalina.jar:8.5.24]
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342) [catalina.jar:8.5.24]
at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:803) [tomcat-coyote.jar:8.5.24]
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66) [tomcat-coyote.jar:8.5.24]
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:790) [tomcat-coyote.jar:8.5.24]
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1459) [tomcat-coyote.jar:8.5.24]
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) [tomcat-coyote.jar:8.5.24]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_162]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_162]
at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-util.jar:8.5.24]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_162]
[INFO] 2020-01-21T03:24:43,708 [pool-15-thread-1] [foobar] [graphql.GraphQLFactory] | No custom GraphQL schema found for site 'foobar'
[INFO] 2020-01-21T03:24:43,986 [pool-15-thread-1] [foobar] [context.SiteContext] | GraphQL schema build completed for site 'foobar' in 1 secs
[INFO] 2020-01-21T03:24:43,987 [pool-15-thread-1] [foobar] [context.SiteContext] | --------------------------------------------------
[INFO] 2020-01-21T03:24:43,987 [pool-15-thread-1] [foobar] [context.SiteContext] | </Initializing context site: foobar>
[INFO] 2020-01-21T03:24:43,987 [pool-15-thread-1] [foobar] [context.SiteContext] | --------------------------------------------------
[WARN] 2020-01-21T03:24:56,103 [Studio_Publisher_Thread_Pool3] [lib.Repository] | close() called when useCnt is already zero for Repository[/Users/vita.alhambra/temp/test3/craftercms/crafter-authoring/data/repos/sites/foobar/sandbox/.git]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment