JSON izleme profili, Bazel'in çağrı sırasında ne kadar zaman harcadığını hızlıca anlamak için çok yararlı olabilir.
Varsayılan olarak Bazel, derleme benzeri tüm komutlar ve sorgular için command-$INOVCATION_ID.profile.gz adlı çıkış tabanına bir profil yazar. Burada $INOVCATION_ID, komutun çağrılma tanımlayıcısıdır. Bazel ayrıca
çıkış tabanında, profili işaret eden command.profile.gz adlı sembolik bir bağlantı
yeni komut dosyasıdır. 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ılır. Bazel, derleme sonrası analiz için varsayılan olarak son 5 profili (--profiles_to_retain ile yapılandırılabilir) çıkış tabanında saklar. --profile ile açıkça bir profil yolu iletilmesi, otomatik çöp toplamayı devre dışı bırakır.
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:
Şekil 1. Örnek profil.
Gezinmek için aşağıdaki klavye denetimlerini kullanabilirsiniz:
- "Seç" için
1tuş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ır" için
2tuşlarına basın yatırım yapmanız önemlidir. Ardından, görünümü taşımak için fareyi sürükleyin. Sol/sağ gitmek içina/dtuşlarını da kullanabilirsiniz. - "Yakınlaştırma" modu için
3tuşuna basın. Ardından, yakınlaştırmak için fareyi sürükleyin. Yakınlaştırmak/uzaklaştırmak içinw/stuşlarını da kullanabilirsiniz. - İ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şuna basın.
bazel analyze-profile
Bazel alt komutu analyze-profile, bir profil biçimini kullanır ve her derleme aşaması için farklı görev türleriyle ilgili kümülatif istatistikleri ve kritik yolun analizini yazdırır.
Örneğin, komutlar
$ bazel build --profile=/tmp/profile.gz //path/to:target
...
$ bazel analyze-profile /tmp/profile.gz
şu biçimde çıktılar 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 kaynak Bazel Çağrı Analiz Aracı Kullanıcı bir profil biçimi kullanır ve bunu iyileştirmek için öneriler yazdırır. derlemenin performansı. Bu analiz, KSA veya KSA kullanılarak yapılabilir. https://analyzer.engflow.com.
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 bir kısmı Bazel'i temsil eder ancak bazı özel satırlar da dahil edilir.
Dahil edilen özel satırlar, profili oluşturulmuş olup farklı işaretler ile özelleştirilebilir.
Şekil 1'de Bazel v5.3.1 ile oluşturulmuş bir profil gösterilmiştir ve aşağıdaki satırları içerir:
action count: Eşzamanlı olarak kaç işlem yapıldığını gösterir. Sonraki slayta geçin tıklayın. Temiz derlemelerde--jobsdeğ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. Bazel'in ne yaptığının üst düzey bir resmini elde etmek için yararlıdır (ör. "Blaze'i başlat", "evaluateTargetPatterns" ve "runAnalysisPhase").Garbage Collector: Küçük ve büyük çöp toplama (GC) verilerini gösterir duraklar.
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. - Özellikle kritik yoldaki yavaş işlemler. 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,
PROCESS_TIMEolmayan ve alışılmadık derecede yüksek bir değer (REMOTE_SETUPveyaFETCHgibi) olup olmadığını kontrol edin. - 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). Bu işlemi optimize etmek için büyük olasılıkla daha fazla paralellik sağlamak amacıyla kural uygulamalarını veya Bazel'i değiştirmeniz gerekir. Bu durum, olağan dışı miktarda GC olduğunda da ortaya çıkabilir.
Profil dosya biçimi
Üst düzey nesne, meta verileri (otherData) ve gerçek izleme verilerini içerir
(traceEvents). Meta veri, çağrı kimliği gibi ek bilgiler içerir
ve Bazel çağrı tarihini belirtin.
Ö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ının (ts) ve sürelerin (dur) birimi mikrosaniyedir. Kategori (cat), ProfilerTask değerinin enum değerlerinden biridir.
Çok kısa ve birbirine yakın olan bazı etkinliklerin birleştirildiğini unutmayın. Etkinliklerin birleştirilmesini engellemek istiyorsanız --noslim_profile parametresini iletin.
Ayrıca bkz. Chrome İzleme Etkinlik Biçimi Spesifikasyonu.