diff mbox series

[bug#68876,maintenance] doc: guix-days-2024: Add notes from the profiling discussion.

Message ID 20e0abfb8d6e7c4584b2c7dea292669f1a63a29f.1707211674.git.leirda@disroot.org
State New
Headers show
Series [bug#68876,maintenance] doc: guix-days-2024: Add notes from the profiling discussion. | expand

Commit Message

Adriel Dumas--Jondeau Feb. 6, 2024, 9:36 a.m. UTC
Change-Id: I57abb36cbab43010f43270e626087cb2cb211c14
---
 doc/guix-days-2024/profiling.md | 81 +++++++++++++++++++++++++++++++++
 1 file changed, 81 insertions(+)
 create mode 100644 doc/guix-days-2024/profiling.md

Hi o/

Here are the notes (unmodified, but we now know why ~,trace~ doesn't
behave the same in a ~guix repl~ than in ~guile~, as it is not the same
VM used in both) we've taken during the profiling session.


base-commit: a4464bd0975c811f18af98f69032b29bddda5b81
prerequisite-patch-id: cefc174692b72660bf5ed4c03442f33dbaf6e784
prerequisite-patch-id: 6b1015959d122056b883e766306b0ff8ba30bde9
prerequisite-patch-id: a3116f7908bc9fdb237e4cde52260856b083596d
prerequisite-patch-id: db1d3d945579284d401944b18fcbc506a000714f
prerequisite-patch-id: 84bff5e4689979f565315edf96250ccc367f8fb8
prerequisite-patch-id: bd69d4115bc86b7d2739e9890625788f1fa5a006
prerequisite-patch-id: 0834ca6f4e7f1ed91b5dcb679096a953a6e36273
prerequisite-patch-id: 9812bf7906d21768559f10f97d8363d2a18fc7e9
prerequisite-patch-id: 68e70365da7db55420105f7cb12c9fffb80d2415

Comments

Ludovic Courtès Feb. 7, 2024, 10:05 p.m. UTC | #1
Hi!

Adriel Dumas--Jondeau <leirda@disroot.org> skribis:

> Hi o/
>
> Here are the notes (unmodified, but we now know why ~,trace~ doesn't
> behave the same in a ~guix repl~ than in ~guile~, as it is not the same
> VM used in both) we've taken during the profiling session.

Heheh, pushed!  Thanks a lot for sharing your notes!

Ludo’.
diff mbox series

Patch

diff --git a/doc/guix-days-2024/profiling.md b/doc/guix-days-2024/profiling.md
new file mode 100644
index 0000000000..f484a2ff3c
--- /dev/null
+++ b/doc/guix-days-2024/profiling.md
@@ -0,0 +1,81 @@ 
+# What is profiling?
+
+- Have an idea of the execution time of part of code
+- Have some kind of flame graph
+- What feels slow (not necessarily what is slow)
+- Where's RAM most used?
+
+# What should be profiled?
+
+- guix authenticate
+- guix system/home
+- guix pull
+- not the compilation itself
+- Guix system start time (like ~systemd-analyse~)
+- Time of deployments
+
+# Why?
+
+- To be able to use Guix on low-end/older devices
+- Building a package is long (because of compilation)
+- Pulling is slow (because of network)
+- Computing Guix derivations (guix system init/reconfigure)
+- Authenticating guix commits (especially with many/big commits)
+
+- It's compelling (for HPC people for instance)
+
+- To know where there's progress to be made
+- ~make~ in the Guix repo is slow so testing is not as immediate as it could be
+- As small/fast finale binary as possible
+- To get a feel of what specs are needed to do actual stuff
+
+# How?
+
+- Guile has ~trace~ / ~profile~ procedure that you could use on Guix procs
+  - How to make a flame graph from this data or any other format?
+- Retrieving some metrics on demand from build systems (via ~guix build~)
+  - to get logs RAM/space/temporal usage during compilation
+- Profiling daemons resources usage from shepherd (exposing those profiling options to users)
+- Making shepherd services to profile ~guix shell --container~
+
+# Starting point
+
+```
+$ guix repl
+(guix-user)> ,use (guix scripts build)
+(guix-user)> ,profile (guix-build "hello")
+%     cumulative   self             
+time   seconds     seconds  procedure
+ 24.00      0.16      0.16  put-string
+ 16.00      0.11      0.11  write
+ 12.00      0.11      0.08  append
+ 12.00      0.08      0.08  get-bytevector-n
+  4.00      0.05      0.03  guix/gexp.scm:809:0:gexp-attribute
+  4.00      0.03      0.03  string->utf8
+  4.00      0.03      0.03  guix/packages.scm:1969:0:package->derivation
+  4.00      0.03      0.03  regexp-exec
+  4.00      0.03      0.03  cons
+  4.00      0.03      0.03  guix/store.scm:1426:4
+  4.00      0.03      0.03  apply-smob/1
+  4.00      0.03      0.03  sort
+(guix-user)> ,trace (guix-build "hello") ; doesn't output any trace for some reason
+/gnu/store/6fbh8phmp3izay6c0dpggpxhcjn4xlm5-hello-2.12.1
+(guix-user)> ^D
+$ guile
+(guile-user)> ,use (guix scripts build)
+(guile-user)> ,trace (guix-build "hello") ; however it does work
+trace: |  (guix-build "hello")
+trace: |  |  (_ (guix scripts) #:ensure #f)
+trace: |  |  (_ #<procedure 7ff4802c5000 at ice-9/boot-9.scm:3241:7 ()>)
+trace: |  |  |  (lock-mutex #<mutex 7ff487d01fc0>)
+...
+trace: |  |  |  |  |  |  (fileno #<output: file /dev/pts/3>)
+trace: |  |  |  |  |  |  1
+trace: |  |  |  |  |  |  (bytevector->pointer@system/foreign #vu8(0 0 0 0 0 0 0 0))
+trace: |  |  |  |  |  |  (bytevector->pointer #vu8(0 0 0 0 0 0 0 0))
+trace: |  |  |  |  |  |  #<pointer 0x7ff48036a7d0>
+```
+
+```sh
+$ guix build --verbosity=<big number> --debug=<big number>
+```