Xwiki 13.10.2 not starting

Help / Discuss
1

Hello everyone.

After a (ubuntu) server restart. xwiki refuses to start and tomcat9 serves a “500”

After digging through a lot of logs I keep seeing this error thrown after a tomcat 9 restart:

Jan  5 16:06:58 gaston tomcat9[5177]: Caused by: org.h2.mvstore.MVStoreException: The write format 1 is smaller than the supported format 2 [2.0.202/5]

Any help would be much appreciated:

Here is the error in it’s context

Jan  5 16:06:50 gaston systemd[1]: Starting Apache Tomcat 9 Web Application Server...
Jan  5 16:06:50 gaston systemd[1]: Started Apache Tomcat 9 Web Application Server.
Jan  5 16:06:50 gaston tomcat9[5177]: Server version name:   Apache Tomcat/9.0.31 (Ubuntu)
Jan  5 16:06:50 gaston tomcat9[5177]: Server built:          Oct 20 2020 12:27:39 UTC
Jan  5 16:06:50 gaston tomcat9[5177]: Server version number: 9.0.31.0
Jan  5 16:06:50 gaston tomcat9[5177]: OS Name:               Linux
Jan  5 16:06:50 gaston tomcat9[5177]: OS Version:            5.4.0-92-generic
Jan  5 16:06:50 gaston tomcat9[5177]: Architecture:          amd64
Jan  5 16:06:50 gaston tomcat9[5177]: Java Home:             /usr/lib/jvm/java-8-openjdk-amd64/jre
Jan  5 16:06:50 gaston tomcat9[5177]: JVM Version:           1.8.0_312-8u312-b07-0ubuntu1~20.04-b07
Jan  5 16:06:50 gaston tomcat9[5177]: JVM Vendor:            Private Build
Jan  5 16:06:50 gaston tomcat9[5177]: CATALINA_BASE:         /var/lib/tomcat9
Jan  5 16:06:50 gaston tomcat9[5177]: CATALINA_HOME:         /usr/share/tomcat9
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Djava.util.logging.config.file=/var/lib/tomcat9/conf/logging.properties
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Djava.awt.headless=true
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Xmx1024m
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Djava.security.egd=file:/dev/./urandom
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Dignore.endorsed.dirs=
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Dcatalina.base=/var/lib/tomcat9
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Dcatalina.home=/usr/share/tomcat9
Jan  5 16:06:50 gaston tomcat9[5177]: Command line argument: -Djava.io.tmpdir=/tmp
Jan  5 16:06:50 gaston tomcat9[5177]: Loaded APR based Apache Tomcat Native library [1.2.23] using APR version [1.6.5].
Jan  5 16:06:50 gaston tomcat9[5177]: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Jan  5 16:06:50 gaston tomcat9[5177]: APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true]
Jan  5 16:06:50 gaston tomcat9[5177]: OpenSSL successfully initialized [OpenSSL 1.1.1f  31 Mar 2020]
Jan  5 16:06:50 gaston tomcat9[5177]: Initializing ProtocolHandler ["http-nio-8080"]
Jan  5 16:06:50 gaston tomcat9[5177]: Initializing ProtocolHandler ["ajp-nio-127.0.0.1-8009"]
Jan  5 16:06:50 gaston tomcat9[5177]: Server initialization in [253] milliseconds
Jan  5 16:06:50 gaston tomcat9[5177]: Starting service [Catalina]
Jan  5 16:06:50 gaston tomcat9[5177]: Starting Servlet engine: [Apache Tomcat/9.0.31 (Ubuntu)]
Jan  5 16:06:50 gaston tomcat9[5177]: Deploying deployment descriptor [/etc/tomcat9/Catalina/localhost/xwiki.xml]
Jan  5 16:06:50 gaston tomcat9[5177]: The path attribute with value [/xwiki] in deployment descriptor [/etc/tomcat9/Catalina/localhost/xwiki.xml] has been ignored
Jan  5 16:06:52 gaston tomcat9[5177]: 2022-01-05 16:06:52,962 [main] INFO  iPropertiesConfigurationSource - Loading [xwiki.properties] from default location [/etc/xwiki/xwiki.properties]
Jan  5 16:06:53 gaston tomcat9[5177]: 2022-01-05 16:06:53,087 [main] INFO  o.x.e.i.ServletEnvironment     - Using permanent directory [/var/lib/xwiki/data]
Jan  5 16:06:55 gaston tomcat9[5177]: 2022-01-05 16:06:55,923 [main] WARN  ltInstalledExtensionRepository - Invalid extension [org.xwiki.platform:xwiki-platform-livedata-webjar/13.3] (InvalidExtensionException: Extension [org.xwiki.platform:xwiki-platform-livedata-webjar/13.3] already exists as a core extension)
Jan  5 16:06:55 gaston tomcat9[5177]: 2022-01-05 16:06:55,924 [main] WARN  ltInstalledExtensionRepository - Invalid extension [org.xwiki.platform:xwiki-platform-livedata-api/13.3] (InvalidExtensionException: Extension [org.xwiki.platform:xwiki-platform-livedata-api/13.3] already exists as a core extension)
Jan  5 16:06:55 gaston tomcat9[5177]: 2022-01-05 16:06:55,924 [main] WARN  ltInstalledExtensionRepository - Invalid extension [org.xwiki.platform:xwiki-platform-livedata-rest/13.3] (InvalidExtensionException: Extension [org.xwiki.platform:xwiki-platform-livedata-rest/13.3] already exists as a core extension)
Jan  5 16:06:55 gaston tomcat9[5177]: 2022-01-05 16:06:55,964 [main] WARN  ltInstalledExtensionRepository - Invalid extension [org.xwiki.platform:xwiki-platform-distribution-flavor-mainwiki/13.10.1] on namespace [wiki:xwiki] (InvalidExtensionException: Dependency [org.xwiki.platform:xwiki-platform-oldcore-[13.10.1]] is incompatible with the core extension [org.xwiki.platform:xwiki-platform-legacy-oldcore/13.10.2])
Jan  5 16:06:55 gaston tomcat9[5177]: 2022-01-05 16:06:55,964 [main] WARN  ltInstalledExtensionRepository - Invalid extension [org.xwiki.platform:xwiki-platform-livedata-livetable/13.3] (InvalidExtensionException: Extension [org.xwiki.platform:xwiki-platform-livedata-livetable/13.3] already exists as a core extension)
Jan  5 16:06:55 gaston tomcat9[5177]: 2022-01-05 16:06:55,983 [main] WARN  ltInstalledExtensionRepository - Invalid extension [org.xwiki.platform:xwiki-platform-distribution-flavor-wiki/13.10.1] on namespace [wiki:sifold] (InvalidExtensionException: Dependency [org.xwiki.platform:xwiki-platform-oldcore-[13.10.1]] is incompatible with the core extension [org.xwiki.platform:xwiki-platform-legacy-oldcore/13.10.2])
Jan  5 16:06:56 gaston tomcat9[5177]: 2022-01-05 16:06:56,002 [main] WARN  ltInstalledExtensionRepository - Invalid extension [org.xwiki.platform:xwiki-platform-distribution-flavor-wiki/13.10.1] on namespace [wiki:installations] (InvalidExtensionException: Dependency [org.xwiki.platform:xwiki-platform-oldcore-[13.10.1]] is incompatible with the core extension [org.xwiki.platform:xwiki-platform-legacy-oldcore/13.10.2])
Jan  5 16:06:58 gaston tomcat9[5177]: 2022-01-05 16:06:58,760 [main] ERROR .o.i.DefaultObservationManager - Failed to lookup listeners
Jan  5 16:06:58 gaston tomcat9[5177]: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component with type [interface org.xwiki.observation.EventListener] and hint [MentionsCreatedEventListener]
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstanceMap(EmbeddableComponentManager.java:245)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstanceList(EmbeddableComponentManager.java:225)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.observation.internal.DefaultObservationManager.initializeListeners(DefaultObservationManager.java:166)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.observation.internal.DefaultObservationManager.getListenersByEvent(DefaultObservationManager.java:132)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.observation.internal.DefaultObservationManager.notify(DefaultObservationManager.java:283)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.internal.StackingComponentEventManager.sendEvent(StackingComponentEventManager.java:151)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.internal.StackingComponentEventManager.flushEvents(StackingComponentEventManager.java:92)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.container.servlet.XWikiServletContextListener.contextInitialized(XWikiServletContextListener.java:124)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4685)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5146)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:631)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1831)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.FutureTask.run(FutureTask.java:266)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:526)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:425)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1576)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:309)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:936)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:841)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1384)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1374)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.FutureTask.run(FutureTask.java:266)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:909)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:262)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardService.startInternal(StandardService.java:421)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:930)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.Catalina.start(Catalina.java:633)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.lang.reflect.Method.invoke(Method.java:498)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:343)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:478)
Jan  5 16:06:58 gaston tomcat9[5177]: Caused by: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component [org.xwiki.mentions.internal.DefaultMentionsEventExecutor] identified by type [interface org.xwiki.mentions.internal.MentionsEventExecutor] and hint [default]
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:204)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getDependencyInstance(EmbeddableComponentManager.java:406)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.createInstance(EmbeddableComponentManager.java:355)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getComponentInstance(EmbeddableComponentManager.java:451)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstanceMap(EmbeddableComponentManager.java:242)
Jan  5 16:06:58 gaston tomcat9[5177]: #011... 48 common frames omitted
Jan  5 16:06:58 gaston tomcat9[5177]: Caused by: org.xwiki.component.phase.InitializationException: Failed to initialize the queue
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.mentions.internal.DefaultMentionsEventExecutor.initialize(DefaultMentionsEventExecutor.java:96)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.InitializableLifecycleHandler.handle(InitializableLifecycleHandler.java:39)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.createInstance(EmbeddableComponentManager.java:365)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getComponentInstance(EmbeddableComponentManager.java:451)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:201)
Jan  5 16:06:58 gaston tomcat9[5177]: #011... 52 common frames omitted
Jan  5 16:06:58 gaston tomcat9[5177]: Caused by: org.h2.mvstore.MVStoreException: The write format 1 is smaller than the supported format 2 [2.0.202/5]
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.DataUtils.newMVStoreException(DataUtils.java:1004)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.getUnsupportedWriteFormatException(MVStore.java:1059)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.readStoreHeader(MVStore.java:878)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.<init>(MVStore.java:455)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.open(MVStore.java:605)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.mentions.internal.async.DefaultMentionsBlockingQueueProvider.initBlockingQueue(DefaultMentionsBlockingQueueProvider.java:69)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.mentions.internal.DefaultMentionsEventExecutor.initialize(DefaultMentionsEventExecutor.java:94)
Jan  5 16:06:58 gaston tomcat9[5177]: #011... 56 common frames omitted
Jan  5 16:06:58 gaston tomcat9[5177]: 2022-01-05 16:06:58,764 [main] ERROR .o.i.DefaultObservationManager - Failed to lookup the Event Listener [MentionsCreatedEventListener] corresponding to the Component registration event for [org.xwiki.mentions.internal.listeners.MentionsCreatedEventListener]. Ignoring the event
Jan  5 16:06:58 gaston tomcat9[5177]: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component [org.xwiki.mentions.internal.listeners.MentionsCreatedEventListener] identified by type [interface org.xwiki.observation.EventListener] and hint [MentionsCreatedEventListener]
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:204)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.observation.internal.DefaultObservationManager.onEventListenerComponentAdded(DefaultObservationManager.java:383)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.observation.internal.DefaultObservationManager.onComponentEvent(DefaultObservationManager.java:354)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.observation.internal.DefaultObservationManager.notify(DefaultObservationManager.java:299)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.internal.StackingComponentEventManager.sendEvent(StackingComponentEventManager.java:151)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.internal.StackingComponentEventManager.flushEvents(StackingComponentEventManager.java:92)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.container.servlet.XWikiServletContextListener.contextInitialized(XWikiServletContextListener.java:124)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4685)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5146)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:631)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1831)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.FutureTask.run(FutureTask.java:266)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:526)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:425)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1576)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:309)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:936)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:841)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1384)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1374)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.FutureTask.run(FutureTask.java:266)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:909)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:262)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardService.startInternal(StandardService.java:421)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:930)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.Catalina.start(Catalina.java:633)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at java.lang.reflect.Method.invoke(Method.java:498)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:343)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:478)
Jan  5 16:06:58 gaston tomcat9[5177]: Caused by: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component [org.xwiki.mentions.internal.DefaultMentionsEventExecutor] identified by type [interface org.xwiki.mentions.internal.MentionsEventExecutor] and hint [default]
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:204)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getDependencyInstance(EmbeddableComponentManager.java:406)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.createInstance(EmbeddableComponentManager.java:355)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getComponentInstance(EmbeddableComponentManager.java:451)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:201)
Jan  5 16:06:58 gaston tomcat9[5177]: #011... 47 common frames omitted
Jan  5 16:06:58 gaston tomcat9[5177]: Caused by: org.xwiki.component.phase.InitializationException: Failed to initialize the queue
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.mentions.internal.DefaultMentionsEventExecutor.initialize(DefaultMentionsEventExecutor.java:96)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.InitializableLifecycleHandler.handle(InitializableLifecycleHandler.java:39)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.createInstance(EmbeddableComponentManager.java:365)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getComponentInstance(EmbeddableComponentManager.java:451)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:201)
Jan  5 16:06:58 gaston tomcat9[5177]: #011... 51 common frames omitted
Jan  5 16:06:58 gaston tomcat9[5177]: Caused by: org.h2.mvstore.MVStoreException: The write format 1 is smaller than the supported format 2 [2.0.202/5]
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.DataUtils.newMVStoreException(DataUtils.java:1004)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.getUnsupportedWriteFormatException(MVStore.java:1059)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.readStoreHeader(MVStore.java:878)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.<init>(MVStore.java:455)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.h2.mvstore.MVStore.open(MVStore.java:605)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.mentions.internal.async.DefaultMentionsBlockingQueueProvider.initBlockingQueue(DefaultMentionsBlockingQueueProvider.java:69)
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.mentions.internal.DefaultMentionsEventExecutor.initialize(DefaultMentionsEventExecutor.java:94)
Jan  5 16:06:58 gaston tomcat9[5177]: #011... 55 common frames omitted
Jan  5 16:06:58 gaston tomcat9[5177]: 2022-01-05 16:06:58,771 [main] ERROR .o.i.DefaultObservationManager - Failed to lookup the Event Listener [MentionsUpdatedEventListener] corresponding to the Component registration event for [org.xwiki.mentions.internal.listeners.MentionsUpdatedEventListener]. Ignoring the event
Jan  5 16:06:58 gaston tomcat9[5177]: org.xwiki.component.manager.ComponentLookupException: Failed to lookup component [org.xwiki.mentions.internal.listeners.MentionsUpdatedEventListener] identified by type [interface org.xwiki.observation.EventListener] and hint [MentionsUpdatedEventListener]
Jan  5 16:06:58 gaston tomcat9[5177]: #011at org.xwiki.component.embed.EmbeddableComponentManager.getInstance(EmbeddableComponentManager.java:204)
2

According to General error: "The write format 1 is smaller than the supported format 2 [2.0.202/5]" [50000-202] HY000/50000 · Issue #3214 · h2database/h2database · GitHub it seems to be that you are starting a newer version of XWiki (using a newer version of MVStore) but with an older MVStore DB.

The MVStore is used to store Mentions but I don’t really know how we handle version upgrades for it. Maybe @mleduc knows more since he implemented it.

1 Like
3

This issue shouldn’t happen unless the wiki has been upgraded. Do you have knowledge of any recent upgrade? If yes, knowing the versions would help :slight_smile:

In the meantime, if you urgently need to restart your server and have access to the filesystem, you can move/remove the mention sub-directory of your permanent directory (see the Configuration guide).
Warning: This directory contains a file that stores a queue of unprocessed mention related events. By removing this directory some user mentions could never reach the notifications inbox of the mentioned user (i.e., if the queue was not empty when the server was stopped). Please proceed with caution.

1 Like
4

Thank you so much @mleduc and @vmassol

Deleting the mentions folder did the trick!

Those issues started when I rebooted the server today. I didn’t do much investigation why xwiki didn’t boot at that time and tried to upgrade to the latest version (from 13.10.1 to 13.10.2). To see if this would fix my issues.

Seeing that this upgrade didn’t fix my issues I investigated deeper and started to see those H2 / MVS issues. But it doesn’t mean that this was also what was preventing me to start xwiki earlier today.

Anyway, so far so good but happy to dig into my logs if you need more info.

Have a great day and thanks again for your help
Alex

1 Like