โปรไฟล์การติดตาม JSON อาจมีประโยชน์มากในการช่วยให้เข้าใจจำนวนเงินที่ Bazel ใช้จ่ายได้อย่างรวดเร็ว ในระหว่างการเรียกใช้
โดยค่าเริ่มต้น สำหรับคำสั่งที่เหมือนบิลด์ทั้งหมดและการค้นหา Bazel จะเขียนโปรไฟล์ดังกล่าวไปยัง
command.profile.gz
คุณสามารถกำหนดค่าว่าจะให้ระบบเขียนโปรไฟล์ด้วย
--generate_json_trace_profile
และตำแหน่งที่จะเขียนด้วย
แฟล็ก --profile
สถานที่ตั้งที่ลงท้ายด้วย .gz
คือ
ที่บีบอัดด้วย GZIP ใช้ธง
--experimental_announce_profile_path
เพื่อพิมพ์เส้นทางไปยังไฟล์นี้ไปยังบันทึก
เครื่องมือ
คุณสามารถโหลดโปรไฟล์นี้ลงใน chrome://tracing
หรือวิเคราะห์และ
หลังการประมวลผลด้วยเครื่องมืออื่นๆ
chrome://tracing
เปิด chrome://tracing
ในแท็บเบราว์เซอร์ Chrome เพื่อดูภาพโปรไฟล์
คลิก "โหลด" แล้วเลือกไฟล์โปรไฟล์ (อาจบีบอัดไว้) สำหรับข้อมูลเพิ่มเติม
ผลลัพธ์โดยละเอียด ให้คลิกช่องที่มุมซ้ายล่าง
ตัวอย่างโปรไฟล์
รูปที่ 1 ตัวอย่างโปรไฟล์
คุณใช้การควบคุมแป้นพิมพ์เหล่านี้เพื่อไปยังส่วนต่างๆ ได้
- กด
1
เพื่อ "เลือก" ในโหมดนี้ คุณสามารถเลือก ช่องที่ต้องการตรวจสอบรายละเอียดกิจกรรม (ดูมุมซ้ายล่าง) เลือกหลายกิจกรรมเพื่อดูข้อมูลสรุปและสถิติแบบรวม - กด
2
เพื่อ "แพน" จากนั้นลากเมาส์เพื่อเลื่อนมุมมอง คุณ สามารถใช้a
/d
เพื่อเลื่อนไปทางซ้าย/ขวาได้ด้วย - กด
3
เพื่อ "ซูม" จากนั้นลากเมาส์เพื่อซูม คุณสามารถ และใช้w
/s
เพื่อซูมเข้า/ออกด้วย - กด
4
เพื่อ "กำหนดเวลา" โหมดที่คุณสามารถวัดระยะทาง ระหว่าง 2 เหตุการณ์ - กด
?
เพื่อดูข้อมูลเกี่ยวกับการควบคุมทั้งหมด
bazel analyze-profile
คำสั่งย่อย Bazel analyze-profile
ใช้รูปแบบโปรไฟล์และพิมพ์สถิติสะสมสำหรับ
ประเภทงานที่แตกต่างกันสำหรับแต่ละระยะของบิลด์และการวิเคราะห์เส้นทางวิกฤติ
เช่น คำสั่ง
$ bazel build --profile=/tmp/profile.gz //path/to:target
...
$ bazel analyze-profile /tmp/profile.gz
อาจแสดงผลของแบบฟอร์มนี้:
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
โอเพนซอร์ส เครื่องมือวิเคราะห์การเรียกใช้ Bazel ใช้รูปแบบโปรไฟล์และพิมพ์คำแนะนำวิธีปรับปรุง ประสิทธิภาพของบิลด์ การวิเคราะห์นี้สามารถดำเนินการโดยใช้ CLI หรือ https://analyzer.engflow.com.
jq
jq
เหมือนกับ sed
สำหรับข้อมูล JSON ตัวอย่างการใช้ jq
ในการดึงข้อมูลทั้งหมด
ระยะเวลาของขั้นตอนการสร้างแซนด์บ็อกซ์ในการดำเนินการภายใน
$ 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
[...]
ข้อมูลโปรไฟล์
โปรไฟล์มีหลายแถว โดยปกติแถวจำนวนมากจะหมายถึง Bazel ชุดข้อความและกิจกรรมที่เกี่ยวข้อง แต่รวมแถวพิเศษบางแถวไว้ด้วย
แถวพิเศษที่รวมอยู่จะขึ้นอยู่กับเวอร์ชันของ Bazel ที่เรียกใช้เมื่อ สร้างโปรไฟล์แล้ว และอาจปรับแต่งด้วยค่าสถานะต่างๆ
รูปที่ 1 แสดงโปรไฟล์ที่สร้างด้วย Bazel v5.3.1 และประกอบด้วยแถวต่อไปนี้
action count
: แสดงจำนวนการกระทำที่เกิดขึ้นพร้อมกันในระหว่างแสดงโฆษณา คลิก เพื่อดูมูลค่าจริง ควรเพิ่มขึ้นจนถึงค่าของ--jobs
อยู่ในสถานะปกติ งานสร้างCPU usage (Bazel)
: สำหรับแต่ละวินาทีของบิลด์ ให้แสดงปริมาณ CPU ที่ Bazel ใช้ (ค่า 1 เท่ากับ 1 แกนที่ไม่ว่าง 100%)Critical Path
: แสดง 1 บล็อกสำหรับการดำเนินการแต่ละรายการบนเส้นทางวิกฤตMain Thread
: ชุดข้อความหลักของ Bazel มีประโยชน์ในการเห็นภาพระดับสูงของ สิ่งที่ Bazel กำลังทำ เช่น "Launch Blaze", "evaluateTargetPatterns" และ "runAnalysisPhase"Garbage Collector
: แสดงการเก็บรวบรวมขยะขนาดเล็กและขนาดใหญ่ (GC) หยุดชั่วคราว
ปัญหาด้านประสิทธิภาพที่พบบ่อย
เมื่อวิเคราะห์โปรไฟล์ประสิทธิภาพ ให้มองหาข้อมูลต่อไปนี้
- ช้ากว่าช่วงการวิเคราะห์ที่คาดไว้ (
runAnalysisPhase
) โดยเฉพาะเมื่อ งานสร้างที่เพิ่มขึ้นเรื่อยๆ ซึ่งอาจเป็นสัญญาณของการใช้กฎที่ไม่มีคุณภาพ ตัวอย่าง 1 ที่ปรับค่าให้คงที่ การโหลดแพ็กเกจอาจช้าเนื่องจาก มีเป้าหมายมากเกินไป มาโครที่ซับซ้อน หรือการหมุนวนซ้ำ - การดำเนินการที่ช้าแต่ละรายการ โดยเฉพาะการดำเนินการในเส้นทางสำคัญ อาจจะเป็น
เพื่อแยกการทำงานขนาดใหญ่ออกเป็นการทำงานย่อยๆ หลายรายการ หรือลด
ชุดทรัพยากร Dependency (สโลแกน) เพื่อเร่งความเร็ว ตรวจหาสิ่งที่ผิดปกติด้วย
ที่มีค่าไม่ใช่
PROCESS_TIME
(เช่นREMOTE_SETUP
หรือFETCH
) - จุดคอขวด ซึ่งเป็นชุดข้อความจำนวนเล็กน้อยที่มีผู้ใช้งานจำนวนมาก ขณะที่รายอื่นๆ ทั้งหมดก็ ไม่มีการใช้งาน / รอผลลัพธ์ (ดูประมาณ 22 และ 29 ในรูปที่ 1) การเพิ่มประสิทธิภาพมักจะต้องใช้การติดตั้งกฎ หรือ Bazel เองเพื่อเพิ่มความสอดคล้องกันมากยิ่งขึ้น กรณีนี้อาจเกิดขึ้นได้เมื่อ มีปริมาณ GC มากผิดปกติ
รูปแบบไฟล์โปรไฟล์
ออบเจ็กต์ระดับบนสุดมีข้อมูลเมตา (otherData
) และข้อมูลการติดตามจริง
(traceEvents
) ข้อมูลเมตามีข้อมูลเพิ่มเติม เช่น รหัสคำขอ
และวันที่เรียกใช้ Bazel
ตัวอย่าง
{
"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},
]
}
การประทับเวลา (ts
) และระยะเวลา (dur
) ในเหตุการณ์การติดตามจะระบุไว้ใน
ไมโครวินาที หมวดหมู่ (cat
) คือค่า enum ค่าหนึ่งของ ProfilerTask
โปรดทราบว่าบางกิจกรรมจะรวมเข้าด้วยกันหากมีขนาดสั้นมากและใกล้เคียงกับ
ระหว่างกัน บัตร
--noslim_json_profile
หากต้องการป้องกันไม่ให้มีการรวมเหตุการณ์
ดูเพิ่มเติม ข้อกำหนดรูปแบบเหตุการณ์การติดตาม Chrome