JSON 트레이스 프로필은 Bazel이 지출한 금액을 빠르게 파악하는 데 매우 유용할 수 있습니다. 시간을 뜻합니다.
기본적으로 모든 빌드와 유사한 명령어 및 쿼리에 대해 Bazel은 이러한 프로필을
command.profile.gz
다음 명령어를 사용하여 프로필을 작성할지 여부를
--generate_json_trace_profile
드림
플래그, 그리고
--profile
플래그. .gz
(으)로 끝나는 위치는 다음과 같습니다.
GZIP으로 압축됩니다. 플래그 사용
--experimental_announce_profile_path
드림
이 파일의 경로를 로그에 출력합니다.
도구
이 프로필을 chrome://tracing
에 로드하거나
다른 도구로 후처리합니다.
chrome://tracing
프로필을 시각화하려면 Chrome 브라우저 탭에서 chrome://tracing
를 엽니다.
'로드'를 클릭합니다. (압축될 수 있는) 프로필 파일을 선택합니다. 자세한 내용은
왼쪽 하단의 상자를 클릭합니다.
프로필 예:
그림 1. 프로필 예
다음 키보드 컨트롤을 사용하여 탐색할 수 있습니다.
- '선택'하려면
1
키를 누르세요. 있습니다. 이 모드에서는 특정 상자를 클릭해 이벤트 세부정보를 확인합니다 (왼쪽 하단 참조). 여러 이벤트를 선택하여 요약 및 집계된 통계를 확인합니다. - '화면 이동'하려면
2
키를 누르세요. 있습니다. 그런 다음 마우스를 드래그하여 보기를 이동합니다. 나a
/d
를 사용하여 왼쪽/오른쪽으로 이동할 수도 있습니다. - '확대/축소'하려면
3
키를 누르세요. 있습니다. 그런 다음 마우스를 드래그하여 확대/축소합니다. 다음을 수행할 수 있습니다.w
/s
를 사용하여 확대/축소할 수도 있습니다. - '타이밍'을 선택하려면
4
키를 누르세요. 거리를 측정할 수 있는 모드입니다. 확인할 수 있습니다. ?
키를 눌러 모든 컨트롤에 대해 알아보세요.
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
는 JSON 데이터의 경우 sed
와 같습니다. 모든 추출을 위한 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을 나타냅니다. 스레드 및 관련 이벤트를 포함하지만 일부 특수 행도 포함됩니다.
포함되는 특수 행은 프로필이 생성되었으며 다른 플래그에 의해 맞춤설정될 수 있습니다.
그림 1은 Bazel v5.3.1로 만든 프로필을 보여주며 다음 행을 포함합니다.
action count
: 진행 중인 동시 작업 수를 표시합니다. 클릭 실제 값을 확인할 수 있습니다. 다음 값까지 상승해야 함: 정리된 상태의--jobs
살펴보겠습니다CPU usage (Bazel)
: 빌드의 매초마다 Bazel에서 사용한 CPU입니다 (값 1은 코어 1개가 100% 사용 중인 것과 같음).Critical Path
: 중요 경로의 각 작업에 대해 하나의 블록을 표시합니다.Main Thread
: Bazel의 기본 스레드입니다. 개괄적인 그림을 그리는 데 유용합니다. 예를 들어 'Launch Blaze', 'evaluateTargetPatterns', 'runAnalysisPhase'가 포함됩니다.Garbage Collector
: 소규모 및 주요 가비지 컬렉션 (GC)을 표시합니다. 일시중지합니다.
일반적인 성능 문제
성능 프로필을 분석할 때는 다음을 확인하세요.
- 예상보다 느린 분석 단계 (
runAnalysisPhase
), 특히 다음에서 느림 빌드됩니다 이는 잘못된 규칙 구현의 징후일 수 있습니다. depset를 평면화하는 예 패키지 로드 속도가 과도한 양의 타겟, 복잡한 매크로 또는 재귀 glob 등이 있습니다. - 개별 느린 작업, 특히 중요한 경로에 있는 작업 그것은
큰 작업을 여러 개의 작은 작업으로 분할하거나
종속 항목 집합을 사용하여 속도를 높입니다. 또한 비정상적인
PROCESS_TIME
외의 높은 값 (예:REMOTE_SETUP
또는FETCH
) - 병목 현상, 즉 소수의 스레드는 바쁘지만 나머지는 모두 결과를 유휴 / 대기 (그림 1의 약 22초 및 29초 참고) 이를 최적화하려면 규칙 구현을 수정해야 할 가능성이 높습니다. Bazel 자체에서 더 많은 동시 로드를 도입할 수 있습니다 이 문제는 GC의 양이 비정상적으로 많습니다.
프로필 파일 형식
최상위 객체에는 메타데이터 (otherData
)와 실제 추적 데이터가 포함되어 있습니다.
(traceEvents
). 메타데이터에 추가 정보(예: 호출 ID)가 포함되어 있습니다.
확인할 수 있습니다
예:
{
"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
)는 ProfilerTask
의 열거형 값 중 하나입니다.
일부 이벤트는 매우 짧고 가까운 거리에 있는 경우 함께 병합됩니다.
서로 통과
--noslim_json_profile
드림
이벤트 병합을 방지하려는 경우
자세한 내용은 Chrome 트레이스 이벤트 형식 사양.