JSON İzleme Profili

Sorun bildirme Kaynağı görüntüleme Nightly · 7.4 . 7.3 · 7.2 · 7.1 · 7.0 · 6.5

JSON izleme profili, Bazel'in ne kadar harcama yaptığını hızlı bir şekilde anlamak için oldukça faydalı olabilir. sırasında açık kalır.

Varsayılan olarak, tüm derleme benzeri komutlar ve sorgular için Bazel, command.profile.gz Bir profilin --generate_json_trace_profile ve içeriğin işaretlendiği yeri --profile işareti. .gz ile biten konumlar GZIP ile sıkıştırıldı. Bayrağı kullan --experimental_announce_profile_path (bu dosyanın yolunu günlüğe yazdırmak).

Araçlar

Bu profili chrome://tracing hizmetine yükleyebilir veya web sitenizin diğer araçlarla örtüştüğünüz aşama.

chrome://tracing

Profili görselleştirmek için Chrome tarayıcı sekmesinde chrome://tracing uygulamasını açın, "Yükle"yi tıklayın ve (potansiyel olarak sıkıştırılmış) profil dosyasını seçin. Daha ayrıntılı sonuçlar için sol alt köşedeki kutuları tıklayın.

Örnek profil:

Örnek profil

Şekil 1. Örnek profil.

Gezinmek için aşağıdaki klavye denetimlerini kullanabilirsiniz:

  • "Seç" için 1 tuşlarına basın yatırım yapmanız önemlidir. Bu modda, etkinlik ayrıntılarını incelemek için belirli kutuları seçebilirsiniz (sol alt köşeye bakın). Özet ve toplu istatistikler almak için birden fazla etkinlik seçin.
  • "Kaydırma" modu için 2 tuşuna basın. Ardından, görünümü taşımak için fareyi sürükleyin. Siz sağa/sola gitmek için a/d tuşlarını da kullanabilirsiniz.
  • "Yakınlaştırma" modu için 3 tuşuna basın. Ardından, yakınlaştırmak için fareyi sürükleyin. Yakınlaştırmak/uzaklaştırmak için w/s tuşlarını da kullanabilirsiniz.
  • İki etkinlik arasındaki mesafeyi ölçebileceğiniz "zamanlama" modu için 4 tuşuna basın.
  • Tüm kontroller hakkında bilgi edinmek için ? tuşuna basın.

bazel analyze-profile

Bazel alt komutu analyze-profile bir profil biçimi kullanır ve şunun için kümülatif istatistikleri yazdırır: her yapı aşaması için farklı görev türleri ve kritik yolun analizi.

Örneğin,

$ bazel build --profile=/tmp/profile.gz //path/to:target
...
$ bazel analyze-profile /tmp/profile.gz

şu şekilde bir çıkış verebilir:

INFO: Profile created on Tue Jun 16 08:59:40 CEST 2020, build ID: 0589419c-738b-4676-a374-18f7bbc7ac23, output base: /home/johndoe/.cache/bazel/_bazel_johndoe/d8eb7a85967b22409442664d380222c0

=== PHASE SUMMARY INFORMATION ===

Total launch phase time         1.070 s   12.95%
Total init phase time           0.299 s    3.62%
Total loading phase time        0.878 s   10.64%
Total analysis phase time       1.319 s   15.98%
Total preparation phase time    0.047 s    0.57%
Total execution phase time      4.629 s   56.05%
Total finish phase time         0.014 s    0.18%
------------------------------------------------
Total run time                  8.260 s  100.00%

Critical path (4.245 s):
       Time Percentage   Description
    8.85 ms    0.21%   _Ccompiler_Udeps for @local_config_cc// compiler_deps
    3.839 s   90.44%   action 'Compiling external/com_google_protobuf/src/google/protobuf/compiler/php/php_generator.cc [for host]'
     270 ms    6.36%   action 'Linking external/com_google_protobuf/protoc [for host]'
    0.25 ms    0.01%   runfiles for @com_google_protobuf// protoc
     126 ms    2.97%   action 'ProtoCompile external/com_google_protobuf/python/google/protobuf/compiler/plugin_pb2.py'
    0.96 ms    0.02%   runfiles for //tools/aquery_differ aquery_differ

Bazel Çağırma Analiz Aracı

Açık kaynak Bazel Çağırma Analizörü, bir profil biçimini kullanır ve derlemenin performansını nasıl iyileştireceğinizle ilgili öneriler yazdırır. Bu analiz, KSA'sı kullanılarak veya https://analyzer.engflow.com adresinde gerçekleştirilebilir.

jq

jq, JSON verileri için sed gibidir. Yerel işlem yürütme sırasında korumalı alan oluşturma adımının tüm sürelerini ayıklamak için jq işlevinin örnek kullanımı:

$ zcat $(../bazel-6.0.0rc1-linux-x86_64 info output_base)/command.profile.gz | jq '.traceEvents | .[] | select(.name == "sandbox.createFileSystem") | .dur'
6378
7247
11850
13756
6555
7445
8487
15520
[...]

Profil bilgileri

Profil birden fazla satır içeriyor. Genellikle satırların büyük kısmı Bazel iş parçacıklarını ve bunlara karşılık gelen etkinlikleri temsil eder ancak bazı özel satırlar da dahil edilir.

Dahil edilen özel satırlar, profil oluşturulurken çağrılan Bazel sürümüne bağlıdır ve farklı işaretlerle özelleştirilebilir.

Şekil 1'de, Bazel v5.3.1 ile oluşturulan ve aşağıdaki satırları içeren bir profil gösterilmektedir:

  • action count: Yayında olan eşzamanlı işlem sayısını gösterir. Gerçek değeri görmek için tıklayın. Temiz derlemelerde --jobs değerine kadar çıkmalıdır.
  • CPU usage (Bazel): Derlemenin her saniyesinde Bazel tarafından kullanılan CPU miktarını gösterir (1 değeri, bir çekirdeğin %100 meşgul olduğu anlamına gelir).
  • Critical Path: Kritik yoldaki her işlem için bir blok görüntüler.
  • Main Thread: Bazel'in ana mesaj dizisi. Projenin genel durumunu görmek için Örneğin, "Launch Blaze", "assessTargetPatterns", ve "runAnalysisPhase" gibi.
  • Garbage Collector: Küçük ve büyük Çöp Toplama (GC) duraklamaları gösterilir.

Sık karşılaşılan performans sorunları

Performans profillerini analiz ederken şunları arayın:

  • Özellikle artımlı derlemelerde analiz aşaması (runAnalysisPhase) beklenenden daha yavaştır. Bu durum, kuralın kötü bir şekilde uygulandığının göstergesi olabilir (ör. depo setlerini düzleştiren bir kural). Paketin yüklenmesi aşırı miktarda hedef, karmaşık makrolar veya özyinelemeli glob'lar.
  • Bağımsız yavaş işlemler, özellikle de kritik yoldakiler. Projenin küçük işlemleri birden fazla küçük işleme bölmek veya bir dizi (geçişli) bağımlılıktan oluşur. Ayrıca, olağandışı bir PROCESS_TIME olmayan yüksek değer (REMOTE_SETUP veya FETCH gibi).
  • Darboğazlar: Diğer tüm iş parçacıları boştayken/sonucu beklerken az sayıda iş parçacığı meşgul olur (Şekil 1'de 22. saniye ve 29. saniyeye bakın). Bunu optimize etmek için büyük olasılıkla kural uygulamalarına dokunmak gerekecektir. veya Bazel'in kendisini tanıtacağını düşünüyor. Bu durum, olağan dışı miktarda GC olduğunda da ortaya çıkabilir.

Profil dosya biçimi

En üst düzey nesne, meta verileri (otherData) ve gerçek izleme verilerini (traceEvents) içerir. Meta veriler, Bazel çağrısının çağrı kimliği ve tarihi gibi ek bilgiler içerir.

Örnek:

{
  "otherData": {
    "build_id": "101bff9a-7243-4c1a-8503-9dc6ae4c3b05",
    "date": "Wed Oct 26 08:22:35 CEST 2022",
    "profile_finish_ts": "1677666095162000",
    "output_base": "/usr/local/google/_bazel_johndoe/573d4be77eaa72b91a3dfaa497bf8cd0"
  },
  "traceEvents": [
    {"name":"thread_name","ph":"M","pid":1,"tid":0,"args":{"name":"Critical Path"}},
    ...
    {"cat":"build phase marker","name":"Launch Blaze","ph":"X","ts":-1306000,"dur":1306000,"pid":1,"tid":21},
    ...
    {"cat":"package creation","name":"foo","ph":"X","ts":2685358,"dur":784,"pid":1,"tid":246},
    ...
    {"name":"thread_name","ph":"M","pid":1,"tid":11,"args":{"name":"Garbage Collector"}},
    {"cat":"gc notification","name":"minor GC","ph":"X","ts":825986,"dur":11000,"pid":1,"tid":11},
    ...
    {"cat":"action processing","name":"Compiling foo/bar.c","ph":"X","ts":54413389,"dur":357594,"pid":1,"args":{"mnemonic":"CppCompile"},"tid":341},
 ]
}

İzleme etkinliklerindeki zaman damgaları (ts) ve süreler (dur) mikrosaniyedir. Kategori (cat), ProfilerTask değerinin enum değerlerinden biridir. Çok kısa ve çok yakın olan bazı etkinliklerin birleştirildiğini unutmayın. birbiri ile; bilet --noslim_json_profile etkinlik birleştirmeyi önlemek istiyorsanız.

Ayrıca bkz. Chrome İzleme Etkinlik Biçimi Spesifikasyonu.