Skip to content

Latest commit

 

History

History
165 lines (109 loc) · 8.07 KB

TROUBLESHOOTING.md

File metadata and controls

165 lines (109 loc) · 8.07 KB

Troubleshooting performance issues

Performance is at the heart of Quarkus.

If you are facing performance issues (runtime or startup issues), and would like to discuss them with the Quarkus Team, you are more than welcome on our mailing list, Zulip chat or Github issue tracker.

To help us troubleshoot your issues, we will need some performance insights from your application.

On Linux or macOS, one of the best way to gather performance insights would be to generate CPU and allocation FlameGraphs via Async Profiler.

If you want a deeper introduction to Async Profiler, do checkout this article.

Installing Async Profiler

To install Async Profiler, go to the release page and download the latest release.

Async Profiler depends on perf_events.
To allow capturing kernel call stacks using perf_events from a non-root process, you must first apply a couple OS configuration options.

For your terminal session:

echo 1 | sudo tee /proc/sys/kernel/perf_event_paranoid
echo 0 | sudo tee /proc/sys/kernel/kptr_restrict

Or permanently using sysctl:

sudo sysctl -w kernel.perf_event_paranoid=1
sudo sysctl -w kernel.kptr_restrict=0

For allocation profiling, you also need to install HotSpot debug symbol (unless you use Oracle JDK that embeds them already).

Depending on your Linux and Java distribution this can be done via:

# Ubuntu/Debian - Java 8
apt install openjdk-8-dbg

# Ubuntu/Debian - Java 11
 apt install openjdk-11-dbg

# On CentOS, RHEL and some other RPM-based distributions - Java 8
debuginfo-install java-1.8.0-openjdk

# On CentOS, RHEL and some other RPM-based distributions - Java 11
debuginfo-install java-11-openjdk

You can also use a fastdebug build of OpenJdk, this kind of build is not for production use (JVM as assertions are enabled), but it includes debug symbols

If needed, see this section in the Async Profiler site for details.

Profiling application runtime with Async Profiler

Async Profiler comes with a Java agent, and a command line.

To profile application while it is running, it is recommended to use the command line as you can choose when to start the profiler and prevent your profile data from being bloated with startup events.
This can be important as any application performs a lot of bootstrapping operation upon startup that won't occur at any other during the application lifecycle.
By starting the profiling on demand, you prevent these bootstrap instructions from being part of the profile data.

When you use the command line, it is advised to use -XX:+UnlockDiagnosticVMOptions -XX:+DebugNonSafepoints JVM flags to have more accurate results.

It is usually advised to profile an application under load, and to start profiling only after some warmup time to allow Java's Just In Time compiler to optimize your application code (not to mention giving the opportunity for database caches to warmup, etc...).
Such load could be created by a load generator tool (ab, wrk2, Gatling, Apache JMeter, ...).

CPU profiling

To start CPU profiling, execute the following command:

/path/to/async-profiler/profiler.sh start -b 4000000 <pid>

-b 4000000 is used to increase the frame buffer size as the default is often too small.

To end profiling and gather the results you can launch the same command with the stop subcommand, this will tells you if the buffer frame was too small.
The output is a text file that is not really usable, so let's use our preferred performance representation: the flame graph.

/path/to/async-profiler/profiler.sh stop -f /tmp/cpu-profile.svg --width 1600 <pid>

It will create an SVG flame graph (Async Profiler automatically detect that you ask for a flame graph thanks to the svg file extension) that you can open in your browser (and even zoom inside it by clicking on a frame).
The example command will create an SVG of 1600 pixels width.

One very useful option is -s (or --simple) that results in simple class names being used instead of fully qualified class names, thus making the flame graph more readable (at cost of not showing the package names of classes).
You can also limit the profiling duration by using -d (or --duration) followed by the duration in seconds. If you use the --duration option, the output file will be created automatically at the end of the duration period. You do not need to explicitly start and stop the profiler.

Allocation profiling

To start allocation profiling, execute the following command:

/path/to/async-profiler/profiler.sh start -b 4000000 -e alloc <pid>

-e (or --event) allow to specify the type of event to profile. The default profile type is CPU, but in this case as we are interested in allocation profiling, we specify alloc as the -e value.

Stopping allocation profiling is done in the same way as for the previously shown CPU profiling.

/path/to/async-profiler/profiler.sh stop -f /tmp/alloc-profile.svg --width 1600 <pid>

Profiling application startup with Async Profiler

When you want to profile application startup, you cannot use the command line tool as you need a way to start the profiler with your application.

For this case, the Java agent is the best tool. It will start profiling when you start the application, then record the profiling data when the application exits.

Some example usages are:

# profile CPU startup
java -agentpath:/path/to/async-profiler/build/libasyncProfiler.so=start,event=cpu,file=startup-cpu-profile.svg,interval=1000000,width=1600,simple\
    -jar my-application.jar

# profile allocation startup
java -agentpath:/path/to/async-profiler/build/libasyncProfiler.so=start,event=alloc,file=/tmp/startup-alloc-profile.svg,interval=1000000,width=1600,simple\
    -jar my-application.jar

Note that short options are not supported inside the agent, you need to use their long versions.

By default, Async Profiler sample events every 10ms. When it comes to profiling / debugging a Quarkus startup issue, this value is often too high as Quarkus starts very fast.
For that reason, it is not uncommon to configure the profiling interval to 1000000ns (i.e. 1ms).

Profiling application dev mode with Async Profiler

For profiling Quarkus dev mode, the Java agent is again necessary. It can be used in the same way as for the production application with the exception that agentpath option needs to be set via the jvm.args system property.

# profile CPU startup
mvn quarkus:dev -Djvm.args="-agentpath:/path/to/async-profiler/build/libasyncProfiler.so=start,event=cpu,file=startup-cpu-profile.svg,interval=1000000,width=1600,simple"

# profile allocation startup
mvn quarkus:dev -Djvm.args="-agentpath:/path/to/async-profiler/build/libasyncProfiler.so=start,event=alloc,file=/tmp/startup-alloc-profile.svg,interval=1000000,width=1600,simple"

You can also configure the jvm.args system property directly inside the quarkus-maven-plugin section of your pom.xml.

And what about Windows?

If you are on Windows, you can still get useful performance insights using JFR - Java Flight Recorder.

The following Java options will enable JFR to record profiling data inside a myrecording.jfr file that can then be used by JMC - Java Mission Control for analysis.

-XX:+FlightRecorder -XX:StartFlightRecording=filename=myrecording.jfr,settings=profile -XX:FlightRecorderOptions=stackdepth=64

Here we configure JFR with a deeper stack depth as the default is usually not enough.