JSON izleme profili, Bazel'ın çağrı sırasında ne üzerinde zaman geçirdiğini hızlı bir şekilde anlamak için çok yararlı olabilir.
Varsayılan olarak, tüm derleme benzeri komutlar ve sorgular için Bazel böyle bir profili
command.profile.gz adresine yazar. Bir profilin --generate_json_trace_profile işaretiyle yazılıp yazılmayacağını ve hangi konuma --profile işaretiyle yazılacağını yapılandırabilirsiniz. .gz ile biten konumlar
GZIP ile sıkıştırılır. Bu dosyanın yolunu günlüğe kaydetmek için işareti --experimental_announce_profile_path kullanın.
Araçlar
Bu profili chrome://tracing içine yükleyebilir veya başka araçlarla analiz edip işleyebilirsiniz.
chrome://tracing
Profili görselleştirmek için bir 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:
Şekil 1. Örnek profil.
Gezinmek için şu klavye denetimlerini kullanabilirsiniz:
- "Seç" modu için
1tuşuna basın. Bu modda, etkinlik ayrıntılarını incelemek için belirli kutuları seçebilirsiniz (sol alt köşeye bakın). Özet ve toplu istatistikleri almak için birden fazla etkinlik seçin. - "Kaydırma" modu için
2tuşuna basın. Ardından, görünümü taşımak için fareyi sürükleyin. Ayrıca sağa/sola gitmek içina/dkullanabilirsiniz. - "Yakınlaştırma" modu için
3tuşuna basın. Ardından, yakınlaştırmak için fareyi sürükleyin. Ayrıca yakınlaştırmak/uzaklaştırmak içinw/skullanabilirsiniz. - İki etkinlik arasındaki mesafeyi ölçebileceğiniz "zamanlama" modu için
4tuşuna basın. - Tüm kontroller hakkında bilgi edinmek için
?tuşlarına basın.
bazel analyze-profile
Bazel analyze-profile alt komutu bir profil biçimi kullanır ve her derleme aşaması ve kritik yolun analizi için farklı görev türleri için kümülatif istatistikler yazdırır.
Örneğin,
$ bazel build --profile=/tmp/profile.gz //path/to:target
...
$ bazel analyze-profile /tmp/profile.gz
şu biçimde çıktı 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ğrı Analiz Aracı
Açık kaynaklı Bazel Invocation Analiz bir profil biçimi kullanır ve derlemenin performansını nasıl iyileştirebileceğinizle ilgili önerileri yazdırır. Bu analiz, KSA kullanılarak veya https://analyzer.engflow.com adresinde gerçekleştirilebilir.
jq
jq, JSON verileri için sed gibidir. Yerel işlem yürütmede korumalı alan oluşturma adımının tüm sürelerini çıkarmak için jq kullanımına ilişkin bir örnek:
$ 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 çok satır içeriyor. Çoğu satır, Bazel ileti dizilerini 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şaretler ile özelleştirilebilir.
Şekil 1'de, Bazel v5.3.1 ile oluşturulmuş bir profil gösterilmiştir ve şu satırlar bulunmaktadır:
action count: Yayın sırasındaki eşzamanlı işlem sayısını gösterir. Gerçek değeri görmek için üzerini tıklayın. Temiz derlemelerde--jobsdeğerine kadar yükselmelidir.CPU usage (Bazel): Derlemenin her saniyesi için 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 ileti dizisi. Bazel'ın neler yaptığına dair genel bir fikir edinmek için yararlıdır; örneğin, "Blaze'i Başlat", "VALUETargetPatterns" ve "runAnalysisStage".Garbage Collector: Küçük ve önemli Çöp Toplama (GC) duraklatmalarını gösterir.
Genel performans sorunları
Performans profillerini analiz ederken şunlara dikkat edin:
- Özellikle artımlı derlemelerde, beklenenden daha yavaş analiz aşaması (
runAnalysisPhase). Bu, kötü bir kural uygulamasının (örneğin, detayı sadeleştiren bir uygulama) göstergesi olabilir. Paketin yüklenmesi aşırı miktarda hedef, karmaşık makrolar veya yinelenen glob'lar nedeniyle yavaş olabilir. - Ayrı ayrı yavaş işlemler, özellikle kritik yolda olanlar. Büyük işlemleri birden çok küçük işleme bölmek veya bunları hızlandırmak için (geçişli) bağımlılık grubunu azaltmak mümkün olabilir.
PROCESS_TIMEolmayan olağandışı bir yüksek değer (REMOTE_SETUPveyaFETCHgibi) olup olmadığını da kontrol edin. - Performans sorunları, diğer tüm iş parçacığı boştayken / sonuç beklerken az sayıda iş parçacığı meşguldür (Şekil 1'de 22'ler ve 29'lar civarına bakın). Bunu optimize etmek, muhtemelen daha fazla paralellik yaratmak için kural uygulamalarına veya Bazel'in kendisine dokunmanızı gerektirecektir. Olağan dışı miktarda GC olduğunda da bu durum görülebilir.
Profil dosyası biçimi
Üst düzey nesne, meta verileri (otherData) ve gerçek iz verilerini (traceEvents) içerir. Meta veriler, ekstra bilgiler (örneğin, Bazel çağrısının çağrı kimliği ve tarihi) 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) mikrosaniye cinsinden verilir. Kategori (cat), ProfilerTask enum değerlerinden biridir.
Bazı etkinliklerin çok kısa ve birbirine yakın olması halinde birleştirildiğini unutmayın. Etkinliklerin birleştirilmesini önlemek istiyorsanız --noslim_json_profile değerini iletin.
Chrome İzleme Etkinlik Biçimi Spesifikasyonu'nu da inceleyin.