JSON izleme profili, Bazel'in çağırma sırasında ne kadar zaman harcadığını hızlıca anlamak için çok faydalı olabilir.
Varsayılan olarak, Bazel tüm derleme benzeri komutlar ve sorgular için command-$INOVCATION_ID.profile.gz adlı çıkış tabanına bir profil yazar. Burada $INOVCATION_ID, komutun çağırma tanımlayıcısıdır. Bazel, çıkış tabanında command.profile.gz adlı bir sembolik bağlantı da oluşturur. Bu bağlantı, son komutun profiline işaret eder. Bir profilin --generate_json_trace_profile işaretiyle yazılıp yazılmayacağını ve hangi konuma yazılacağını --profile işaretiyle yapılandırabilirsiniz. .gz ile biten konumlar GZIP ile sıkıştırılır. Bazel, derleme sonrası analiz için varsayılan olarak çıkış tabanında --profiles_to_retain ile yapılandırılabilen son 5 profili saklar. --profile ile profil yolunu açıkça iletmek, otomatik çöp toplama işlemini devre dışı bırakır.
Araçlar
Bu profili chrome://tracing'a yükleyebilir veya başka araçlarla analiz edip sonradan işleyebilirsiniz.
chrome://tracing
Profili görselleştirmek için Chrome tarayıcı sekmesinde chrome://tracing adresini açın,
"Yükle"yi tıklayın ve (sıkıştırılmış olabilecek) profil dosyasını seçin. Daha ayrıntılı sonuçlar için sol alt köşedeki kutuları tıklayın.
Örnek profil:
1.şekil Örnek profil.
Gezinmek için aşağıdaki klavye kontrollerini 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 istatistikler 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. Sola/sağa 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 veya 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
?düğmesine 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,
$ bazel build --profile=/tmp/profile.gz //path/to:target
...
$ bazel analyze-profile /tmp/profile.gz
şeklinde bir çıkış elde edilebilir:
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 Analyzer, 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 işlevi gibidir. Yerel işlem yürütmesinde sanal alan oluşturma adımının tüm sürelerini ayıklamak 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 fazla satır içeriyor. Satırların büyük kısmı genellikle Bazel iş parçacıklarını ve bunlara karşılık gelen etkinlikleri temsil eder ancak bazı özel satırlar da bulunur.
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şturulmuş bir profil gösterilmektedir. Bu profil aşağıdaki satırları içerir:
action count: Kaç eşzamanlı işlemin devam ettiğini gösterir. Gerçek değeri görmek için bu metriği tıklayın. Temiz derlemelerde--jobsdeğerine kadar çıkmalıdır.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österir.Main Thread: Bazel'in ana iş parçacığı. Bazel'in ne yaptığına dair üst düzey bir resim elde etmek için yararlıdır. Örneğin, "Launch Blaze", "evaluateTargetPatterns" ve "runAnalysisPhase".Garbage Collector: Küçük ve büyük çöp toplama (GC) duraklamalarını gösterir.
Sık karşılaşılan performans sorunları
Performans profillerini analiz ederken şunlara dikkat edin:
- Beklenenden daha yavaş analiz aşaması (
runAnalysisPhase), özellikle de artımlı derlemelerde. Bu durum, örneğin bağımlılık kümelerini düzleştiren bir kuralın kötü uygulanmasının işareti olabilir. Paket yükleme, aşırı sayıda hedef, karmaşık makro veya yinelemeli glob nedeniyle yavaş olabilir. - Tek tek yavaş işlemler, özellikle de kritik yoldaki işlemler. Büyük işlemleri birden fazla küçük işleme bölmek veya geçişli bağımlılıklar kümesini azaltmak, işlemleri hızlandırmak için kullanılabilir. Ayrıca,
PROCESS_TIMEgibi alışılmadık derecede yüksek birPROCESS_TIMEolup olmadığını da kontrol edin (ör.REMOTE_SETUPveyaFETCH). - Darboğazlar: Diğer tüm iş parçacıkları boşta dururken / sonucu beklerken az sayıda iş parçacığı meşgul (Şekil 1'de yaklaşık 22. ve 29. saniyelerde gösterilmiştir). Bunu optimize etmek için büyük olasılıkla daha fazla paralellik sağlamak üzere kural uygulamalarına veya Bazel'e dokunmanız gerekir. Bu durum, olağan dışı miktarda GC olduğunda da yaşanabilir.
Profil dosya biçimi
En üst düzey nesne, meta verileri (otherData) ve gerçek izleme verilerini (traceEvents) içerir. Meta veriler, ek bilgiler (ör. Bazel çağrısının 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.
Çok kısa ve birbirine yakın olan bazı etkinliklerin birleştirildiğini unutmayın. Etkinliklerin birleştirilmesini önlemek istiyorsanız --noslim_profile iletin.
Ayrıca Chrome İzleme Etkinliği Biçimi Spesifikasyonu'na da bakın.