Xwiki 14.1 not starting after upgrading from 13.10,2

Help / Discuss
#1

Hello Everyone,

xwiki is giving me hard time after upgrading my ubuntu server’s xwiki to 14.1 using apt.

After upgrading the wiki to 14.1 I briefly see the install screen with some percentage going up until ~ 18% and then a 500.

See attached logs (it seems like a XML parsing issue, not sure about what file or document)
syslog.txt (70.5 KB)

I tried 14.0 and got the same issue so I downgraded back to 13.10.2

During my last upgrade attempt I also ran into some issues, could it be related? See this post:
Xwiki 13.10.2 not starting

As always thanks a lot for your help

Alex

#2

Hi Everyone, I’m just bumping this message to the top of the pile.

In the log I see multiple errors like: PreferencesConfigurationSource - Failed to access configuration value for property

And also this one: Error parsing xml

I feel like my XML config files are fine, as downgrading to the last version (keeping the same xml config files) works

Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: com.xpn.xwiki.XWikiException: Error number 2002 in 2: Error parsing xml
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:452)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.classes.BaseClass.fromXML(BaseClass.java:533)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1053)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 64 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: java.lang.RuntimeException: Failed to load component for type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] for hint [default]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:753)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:776)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:729)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:450)

Maybe this will ring a bell.

Thanks for your help

Alex

#3

Hi, it seems your stack trace is not complete (Caused by: java.lang.RuntimeException: Failed to load component for type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] for hint [default] is not the root cause). Could you paste a complete one?

#4

Hi, Thanks for checking in,.

I just pasted the top of the syslog file of the original post but I can’t post the whole stack as it is ~60k char long

I reattached the full log here:

syslog.txt (70.5 KB)

Here is the first few lines of the stacktrace.

Mar 22 18:12:19 gaston tomcat9[9934]: 2022-03-22 18:12:19,686 [Mail Sender Thread] ERROR assDocumentConfigurationSource - Failed to access configuration value for property [sendWaitTime]. Ignoring by returning null
Mar 22 18:12:19 gaston tomcat9[9934]: com.xpn.xwiki.XWikiException: Error number 3202 in 3: Exception while reading document [xwiki:Mail.MailConfig]
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1152)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiCacheStore.loadXWikiDoc(XWikiCacheStore.java:394)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2137)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2199)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.getDocument(AbstractDocumentConfigurationSource.java:225)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.getBaseObject(AbstractDocumentConfigurationSource.java:216)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.getBaseProperty(AbstractDocumentConfigurationSource.java:230)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.getPropertyValue(AbstractDocumentConfigurationSource.java:348)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.getProperty(AbstractDocumentConfigurationSource.java:328)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.mail.internal.configuration.DefaultMailSenderConfiguration.getSendWaitTime(DefaultMailSenderConfiguration.java:317)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.mail.internal.thread.SendMailRunnable.waitSendWaitTime(SendMailRunnable.java:193)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.mail.internal.thread.SendMailRunnable.runInternal(SendMailRunnable.java:122)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.mail.internal.thread.SendMailRunnable.run(SendMailRunnable.java:80)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at java.base/java.lang.Thread.run(Thread.java:829)
Mar 22 18:12:19 gaston tomcat9[9934]: Caused by: com.xpn.xwiki.XWikiException: Error number 3202 in 3: Exception while reading document [xwiki:Mail.SendMailConfigClass]
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1152)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiCacheStore.loadXWikiDoc(XWikiCacheStore.java:394)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2137)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2199)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getXClass(XWiki.java:7115)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.classes.BaseClass.newCustomClassInstance(BaseClass.java:1312)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1094)
Mar 22 18:12:19 gaston tomcat9[9934]: #011... 13 common frames omitted
Mar 22 18:12:19 gaston tomcat9[9934]: Caused by: com.xpn.xwiki.XWikiException: Error number 2002 in 2: Error parsing xml
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:452)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.classes.BaseClass.fromXML(BaseClass.java:533)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1053)
Mar 22 18:12:19 gaston tomcat9[9934]: #011... 19 common frames omitted
Mar 22 18:12:19 gaston tomcat9[9934]: Caused by: java.lang.RuntimeException: Failed to load component for type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] for hint [default]
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:753)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:776)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:729)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:450)
Mar 22 18:12:19 gaston tomcat9[9934]: #011... 21 common frames omitted
Mar 22 18:12:19 gaston tomcat9[9934]: Caused by: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component [com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] identified by type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] and hint [default]
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:204)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.component.internal.multi.DelegateComponentManager.getInstance(DelegateComponentManager.java:83)
Mar 22 18:12:19 gaston tomcat9[9934]: message repeated 4 times: [ #011at org.xwiki.component.internal.multi.DelegateComponentManager.getInstance(DelegateComponentManager.java:83)]
Mar 22 18:12:19 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:751)
Mar 22 18:12:19 gaston tomcat9[9934]: #011... 24 common frames omitted
Mar 22 18:12:19 gaston tomcat9[9934]: Caused by: org.xwiki.component.manager.ComponentLookupException: Can't find descriptor for the component with type [interface org.xwiki.filter.input.InputFilterStreamFactory] and hint [xwiki+xar/1.5]
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:212)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getDependencyInstance(EmbeddableComponentManager.java:406)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.createInstance(EmbeddableComponentManager.java:355)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getComponentInstance(EmbeddableComponentManager.java:451)
Mar 22 18:12:19 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:201)
Mar 22 18:12:19 gaston tomcat9[9934]: #011... 30 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: 2022-03-22 18:12:20,911 [ajp-nio-127.0.0.1-8009-exec-5 - https://sifklic.sif-revetement.com/xwiki/bin/view/Main/] ERROR PreferencesConfigurationSource - Failed to access configuration value for property [url.format]. Ignoring by returning null
Mar 22 18:12:20 gaston tomcat9[9934]: com.xpn.xwiki.XWikiException: Error number 3202 in 3: Exception while reading document [xwiki:XWiki.XWikiPreferences]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1152)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiCacheStore.loadXWikiDoc(XWikiCacheStore.java:394)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2137)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2199)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractXWikiPreferencesConfigurationSource.getBaseObject(AbstractXWikiPreferencesConfigurationSource.java:86)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractXWikiPreferencesConfigurationSource.getBaseProperty(AbstractXWikiPreferencesConfigurationSource.java:139)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractXWikiPreferencesConfigurationSource.getBaseProperty(AbstractXWikiPreferencesConfigurationSource.java:156)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.getPropertyValue(AbstractDocumentConfigurationSource.java:348)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.containsKey(AbstractDocumentConfigurationSource.java:203)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractCompositeConfigurationSource.containsKey(AbstractCompositeConfigurationSource.java:45)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractCompositeConfigurationSource.getProperty(AbstractCompositeConfigurationSource.java:95)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultURLConfiguration.getURLFormatId(DefaultURLConfiguration.java:58)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultResourceTypeResolver.resolve(DefaultResourceTypeResolver.java:70)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultResourceTypeResolver.resolve(DefaultResourceTypeResolver.java:45)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.initializeResourceFromURL(XWiki.java:1050)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getXWiki(XWiki.java:919)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:377)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:288)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.LegacyActionServlet.service(LegacyActionServlet.java:115)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at javax.servlet.http.HttpServlet.service(HttpServlet.java:741)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.ActionFilter.doFilter(ActionFilter.java:122)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.wysiwyg.filter.ConversionFilter.doFilter(ConversionFilter.java:61)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:53)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.container.servlet.filters.internal.SetHTTPHeaderFilter.doFilter(SetHTTPHeaderFilter.java:63)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.container.servlet.filters.internal.SavedRequestRestorerFilter.doFilter(SavedRequestRestorerFilter.java:208)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.container.servlet.filters.internal.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:111)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.resource.servlet.RoutingFilter.doFilter(RoutingFilter.java:132)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:202)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:541)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:688)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.coyote.ajp.AjpProcessor.service(AjpProcessor.java:415)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1639)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at java.base/java.lang.Thread.run(Thread.java:829)
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: com.xpn.xwiki.XWikiException: Error number 2002 in 2: Error parsing xml
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:452)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.classes.BaseClass.fromXML(BaseClass.java:533)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1053)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 59 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: java.lang.RuntimeException: Failed to load component for type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] for hint [default]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:753)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:776)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:729)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:450)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 61 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component [com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] identified by type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] and hint [default]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:204)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.internal.multi.DelegateComponentManager.getInstance(DelegateComponentManager.java:83)
Mar 22 18:12:20 gaston tomcat9[9934]: message repeated 4 times: [ #011at org.xwiki.component.internal.multi.DelegateComponentManager.getInstance(DelegateComponentManager.java:83)]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:751)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 64 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: org.xwiki.component.manager.ComponentLookupException: Can't find descriptor for the component with type [interface org.xwiki.filter.input.InputFilterStreamFactory] and hint [xwiki+xar/1.5]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:212)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getDependencyInstance(EmbeddableComponentManager.java:406)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.createInstance(EmbeddableComponentManager.java:355)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getComponentInstance(EmbeddableComponentManager.java:451)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:201)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 70 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: 2022-03-22 18:12:20,914 [ajp-nio-127.0.0.1-8009-exec-5 - https://sifklic.sif-revetement.com/xwiki/bin/view/Main/] ERROR PreferencesConfigurationSource - Failed to access configuration value for property [url.format]. Ignoring by returning null
Mar 22 18:12:20 gaston tomcat9[9934]: com.xpn.xwiki.XWikiException: Error number 3202 in 3: Exception while reading document [xwiki:XWiki.XWikiPreferences]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1152)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiCacheStore.loadXWikiDoc(XWikiCacheStore.java:394)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2137)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getDocument(XWiki.java:2199)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractXWikiPreferencesConfigurationSource.getBaseObject(AbstractXWikiPreferencesConfigurationSource.java:86)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractXWikiPreferencesConfigurationSource.getBaseProperty(AbstractXWikiPreferencesConfigurationSource.java:139)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractXWikiPreferencesConfigurationSource.getBaseProperty(AbstractXWikiPreferencesConfigurationSource.java:156)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.getPropertyValue(AbstractDocumentConfigurationSource.java:348)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractDocumentConfigurationSource.containsKey(AbstractDocumentConfigurationSource.java:203)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractCompositeConfigurationSource.containsKey(AbstractCompositeConfigurationSource.java:45)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.configuration.internal.AbstractCompositeConfigurationSource.getProperty(AbstractCompositeConfigurationSource.java:95)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultURLConfiguration.getURLFormatId(DefaultURLConfiguration.java:58)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultStringResourceTypeResolver.resolve(DefaultStringResourceTypeResolver.java:68)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultStringResourceTypeResolver.resolve(DefaultStringResourceTypeResolver.java:45)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.AbstractExtendedURLResourceTypeResolver.resolve(AbstractExtendedURLResourceTypeResolver.java:88)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.standard.StandardExtendedURLResourceTypeResolver.resolve(StandardExtendedURLResourceTypeResolver.java:100)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.standard.StandardExtendedURLResourceTypeResolver.resolve(StandardExtendedURLResourceTypeResolver.java:57)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultResourceTypeResolver.resolve(DefaultResourceTypeResolver.java:76)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.url.internal.DefaultResourceTypeResolver.resolve(DefaultResourceTypeResolver.java:45)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.initializeResourceFromURL(XWiki.java:1050)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.XWiki.getXWiki(XWiki.java:919)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:377)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:288)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.LegacyActionServlet.service(LegacyActionServlet.java:115)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at javax.servlet.http.HttpServlet.service(HttpServlet.java:741)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.ActionFilter.doFilter(ActionFilter.java:122)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.wysiwyg.filter.ConversionFilter.doFilter(ConversionFilter.java:61)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:53)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.container.servlet.filters.internal.SetHTTPHeaderFilter.doFilter(SetHTTPHeaderFilter.java:63)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.container.servlet.filters.internal.SavedRequestRestorerFilter.doFilter(SavedRequestRestorerFilter.java:208)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.container.servlet.filters.internal.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:111)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.resource.servlet.RoutingFilter.doFilter(RoutingFilter.java:132)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:202)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:541)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:688)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.coyote.ajp.AjpProcessor.service(AjpProcessor.java:415)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1639)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at java.base/java.lang.Thread.run(Thread.java:829)
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: com.xpn.xwiki.XWikiException: Error number 2002 in 2: Error parsing xml
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:452)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.classes.BaseClass.fromXML(BaseClass.java:533)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.store.XWikiHibernateStore.loadXWikiDoc(XWikiHibernateStore.java:1053)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 64 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: java.lang.RuntimeException: Failed to load component for type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] for hint [default]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:753)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:776)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:729)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.objects.BaseElement.fromXML(BaseElement.java:450)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 66 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component [com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] identified by type [class com.xpn.xwiki.internal.filter.XWikiDocumentFilterUtils] and hint [default]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:204)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.internal.multi.DelegateComponentManager.getInstance(DelegateComponentManager.java:83)
Mar 22 18:12:20 gaston tomcat9[9934]: message repeated 4 times: [ #011at org.xwiki.component.internal.multi.DelegateComponentManager.getInstance(DelegateComponentManager.java:83)]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at com.xpn.xwiki.web.Utils.getComponent(Utils.java:751)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 69 common frames omitted
Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: org.xwiki.component.manager.ComponentLookupException: Can't find descriptor for the component with type [interface org.xwiki.filter.input.InputFilterStreamFactory] and hint [xwiki+xar/1.5]

Does that work for you?
Alex

#5

Actually what’s important are the last lines :wink:

From your attached file it’s:

Mar 22 18:12:20 gaston tomcat9[9934]: Caused by: org.xwiki.component.manager.ComponentLookupException: Can't find descriptor for the component with type [interface org.xwiki.filter.input.InputFilterStreamFactory] and hint [xwiki+xar/1.5]
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:212)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getDependencyInstance(EmbeddableComponentManager.java:406)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.createInstance(EmbeddableComponentManager.java:355)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getComponentInstance(EmbeddableComponentManager.java:451)
Mar 22 18:12:20 gaston tomcat9[9934]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:201)
Mar 22 18:12:20 gaston tomcat9[9934]: #011... 86 common frames omitted

So this seems to mean you don’t have the xwiki+xar/1.5 filter steam component. Maybe go the Extension Manager UI and make sure you’re up to date on the extension version you use?

#6

Thanks!

I’m not 100% sure about what’s the next step are.

Are we talking about this extension?
image
Should I install it?

#7

Support for xwiki+xar/1.5 is in the WAR (the xwiki-platform-filter-stream-xar JAR file to be precise), not in some contrib extension, so you won’t be able to fix this with Extension Manager.

But having a missing JAR file is extremely unlikely with the Debian package, unless the installation did not actually go through, and you got an error in the middle of it that you did not notice.

It’s more probably a consequence of another problem and what we would really need from you is the very first error you have in the log after a restart, anything else after that could be a distant side effect that might not say much of the root problem.

#8

@tmortagne Thanks for your help as well, here are the first 10000 lines of log starting right at the tomcat restart

syslog_truncated.txt (3.2 MB)

You can see on line 4134:

Mar 31 15:56:22 gaston tomcat9[318712]: Context [/xwiki] startup failed due to previous errors

There is a lot more where that comes from (~500 MB of logs) So if you need more info just let me know

#9

Hi @alexBaizeau
Took a look to provided log, found string:

Mar 31 15:56:22 gaston tomcat9[318712]: Unable to add the resource at [/WEB-INF/classes/META-INF/services/org.xwiki.component.embed.LifecycleHandler] to the cache for web application [{1}] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache

Do you have any free space on partition with tomcat?

Regards, Vadim

#10

Thanks @amadis
It looks like there is plenty of room here is the output of df -h
image

#11

Hi @alexBaizeau
Also found such string:

Mar 31 15:56:19 gaston tomcat9[318712]: The path attribute with value [/xwiki] in deployment descriptor [/etc/tomcat9/Catalina/localhost/xwiki.xml] has been ignored

Looks like that file /etc/tomcat9/Catalina/localhost/xwiki.xml has parameter with path /xwiki, but you have no /xwiki directory. Try to create /xwiki directory, set permissions allows tomcat write to it (or maybe 0777 for guarantee) and restart tomcat.

Regards, Vadim

#12

It’s very strange, your log suggests that you have 13.10.1, 13.10.2 and 14.2.1 JARs at the same time in /usr/lib/xwiki/WEB-INF/lib/. I don’t see anything about 14.1, but I guess you tried to install 14.2.1 this time (since that’s the last version in stable repository now) ?

It’s definitely not what I see in the Debian package, so you seem to have something special here. You sure you installed 13.10.1 and 13.10.2 using Debian packages ? If it was the case dpkg would have removed those files during upgrade.

2 Likes
#13

@tmortagne Thanks for looking into it. We usually only use dpkg/apt get to install xwiki, we had to downgrade a couple of times when we run into some issues while upgrading by specifying a version to apt get.

We also back up this folder as part of our hand rolled xwiki backup script and had to restore xwiki to a known working version during our upgrades hiccups.

Is there a suggested way to clean up this folder once and for all?
Could the files be leftover of dpkg downgrades?

Thanks again

#14

No, I don’t think so. It’s more probably related to your backup process, which probably keep too much or you restored a backup that was not corresponding to the dpkg index (restored 13.10.1 while dpkg though that 13.10.2 was installed or something like this).

You should uninstall all xwiki-* packages, delete /usr/lib/xwiki/ and reinstall it.

#15

I created a similar problem for myself when I restored a backup to revert to a previous version of xwiki because of a failed upgrade.

The end result was a # of duplicate jar files in the lib directory that were causing a number of problems. I finally solved it in the end by installing the correct version xwiki I was trying to work with into a temporary machine, getting a list of all the jar files and then using that list to determine what files I should keep/have in my restored version and which ones should be deleted.

This worked for me and I’ve not had a problem since then.

#16

You can ignore this error. It’s harmless.

#17

Thanks Everyone for your input and particularly @tmortagne the instructions worked and we are now on 14.2.1.

Sorry for the delay. I finally got around to do it today.