Confluence doesent start after Update to 7.18.2

[Sascha Zänker]

Hey there,

after i updated my confluence to 7.18.2 i cant reach the webinterface anymore because of a timeout.

I am using confluence behind a nginx reverse proxy.

The catalina.out file looks like this:

NOTE: Picked up JDK_JAVA_OPTIONS: --add-opens=java.base/java.lang=ALL-UNNAMED --add-opens=java.base/java.io=ALL-UNNAMED --add-opens=java.base/java.util=ALL-UNNAMED --add-opens=java.base/java.util.concurre>
[0.001s][warning][gc] -Xloggc is deprecated. Will use -Xlog:gc:/usr/confluence/logs/gc-2022-07-11_11-20-45.log instead.
11-Jul-2022 11:20:46.449 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server] failed to set property [debug] to [0]
11-Jul-2022 11:20:46.513 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Connector] failed to set property [debug] to [0]
11-Jul-2022 11:20:46.534 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine] failed to set property [debug] to [0]
11-Jul-2022 11:20:46.539 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine/Host] failed to set property [debug] to [0]
11-Jul-2022 11:20:46.582 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine/Host/Context] failed to set property [debug] to [0]
11-Jul-2022 11:20:46.635 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine/Host/Context] failed to set property [debug] to [0]
11-Jul-2022 11:20:46.980 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8090"]
11-Jul-2022 11:20:47.011 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [685] milliseconds
11-Jul-2022 11:20:47.019 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat-Standalone]
11-Jul-2022 11:20:47.020 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.63]
11-Jul-2022 11:20:48.244 INFO [Catalina-utility-2] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a comp>
11-Jul-2022 11:20:48.441 INFO [Catalina-utility-2] org.apache.catalina.core.ApplicationContext.log 1 Spring WebApplicationInitializers detected on classpath
11-Jul-2022 11:20:48.640 INFO [Catalina-utility-2] org.apache.catalina.core.ApplicationContext.log Initializing Spring DispatcherServlet 'dispatcher'
11:20:48 [Catalina-utility-2] o.a.c.c.C.[.[.[/synchrony-proxy][INFO] log - Initializing Spring DispatcherServlet 'dispatcher'
2022-07-11 11:21:03,726 INFO [Catalina-utility-1] [com.atlassian.confluence.lifecycle] contextInitialized Starting Confluence 7.18.2 [build 8804 based on commit hash 839bd89917830b046a4dfe4ed719125a82c689d>
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.apache.felix.framework.ext.ClassPathExtenderFactory$DefaultClassLoaderExtender (file:/usr/confluence/confluence/WEB-INF/lib/org.apache.felix.framework-5.6.12.jar) >
WARNING: Please consider reporting this to the maintainers of org.apache.felix.framework.ext.ClassPathExtenderFactory$DefaultClassLoaderExtender
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
Exception in thread "I/O dispatcher 4" java.lang.OutOfMemoryError: Java heap space
at java.base/java.util.Collections$UnmodifiableCollection.iterator(Unknown Source)
at org.apache.http.impl.nio.reactor.BaseIOReactor.validate(BaseIOReactor.java:210)
at org.apache.http.impl.nio.reactor.AbstractIOReactor.execute(AbstractIOReactor.java:280)
at org.apache.http.impl.nio.reactor.BaseIOReactor.execute(BaseIOReactor.java:104)
at org.apache.http.impl.nio.reactor.AbstractMultiworkerIOReactor$Worker.run(AbstractMultiworkerIOReactor.java:591)
at java.base/java.lang.Thread.run(Unknown Source)
NOTE: Picked up JDK_JAVA_OPTIONS: --add-opens=java.base/java.lang=ALL-UNNAMED --add-opens=java.base/java.io=ALL-UNNAMED --add-opens=java.base/java.util=ALL-UNNAMED --add-opens=java.base/java.util.concurre>
[0.001s][warning][gc] -Xloggc is deprecated. Will use -Xlog:gc:/usr/confluence/logs/gc-2022-07-12_10-45-02.log instead.
12-Jul-2022 10:45:12.086 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server] failed to set property [debug] to [0]
12-Jul-2022 10:45:12.233 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Connector] failed to set property [debug] to [0]
12-Jul-2022 10:45:12.317 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine] failed to set property [debug] to [0]
12-Jul-2022 10:45:12.324 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine/Host] failed to set property [debug] to [0]
12-Jul-2022 10:45:12.876 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine/Host/Context] failed to set property [debug] to [0]
12-Jul-2022 10:45:15.489 WARNING [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin Match [Server/Service/Engine/Host/Context] failed to set property [debug] to [0]
12-Jul-2022 10:45:16.235 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8090"]
12-Jul-2022 10:45:16.315 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [4688] milliseconds
12-Jul-2022 10:45:16.324 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat-Standalone]
12-Jul-2022 10:45:16.325 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.63]
12-Jul-2022 10:45:24.095 INFO [Catalina-utility-2] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a comp>
12-Jul-2022 10:45:25.036 INFO [Catalina-utility-2] org.apache.catalina.core.ApplicationContext.log 1 Spring WebApplicationInitializers detected on classpath
12-Jul-2022 10:45:25.527 INFO [Catalina-utility-2] org.apache.catalina.core.ApplicationContext.log Initializing Spring DispatcherServlet 'dispatcher'
10:45:25 [Catalina-utility-2] o.a.c.c.C.[.[.[/synchrony-proxy][INFO] log - Initializing Spring DispatcherServlet 'dispatcher'
2022-07-12 10:46:12,426 INFO [Catalina-utility-1] [com.atlassian.confluence.lifecycle] contextInitialized Starting Confluence 7.18.2 [build 8804 based on commit hash 839bd89917830b046a4dfe4ed719125a82c689d>
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.apache.felix.framework.ext.ClassPathExtenderFactory$DefaultClassLoaderExtender (file:/usr/confluence/confluence/WEB-INF/lib/org.apache.felix.framework-5.6.12.jar) >
WARNING: Please consider reporting this to the maintainers of org.apache.felix.framework.ext.ClassPathExtenderFactory$DefaultClassLoaderExtender
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release

[AAMIR ANSARI · Accepted solution]

Hi Sascha,

I could locate the OutOfMemory error in the logs.

Exception in thread "I/O dispatcher 4" java.lang.OutOfMemoryError: Java heap space

The OOME is common during upgrades if the re-upgrade recovery process is enabled.

I would suggest you disable the pre-upgrade recovery file by following the steps under listed in below document

• https://confluence.atlassian.com/confkb/upgrading-hangs-when-generating-pre-upgrade-recovery-file-590938663.html

Let me know how it goes.

Thanks,

Aamir

[Sascha Zänker]

Thank you so much.

I have increased the Java Memory and now everything works as aspected.