[06:07:23.829] New invocation is queued and will start shortly
[06:07:24.934] Starting the invocation (attempt 1)
[06:07:24.961] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b"
[06:07:24.961] Popped gitiles commit info from properties and tags
[06:07:24.961] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:07:24.961] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:07:24.962] Buildbucket request:
{
"requestId": "8875275377328240336",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8875275377328240336",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bccd34f32342e0f299d528ffbb70c825eee983d5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bccd34f32342e0f299d528ffbb70c825eee983d5",
"title": "bccd34f32342e0f299d528ffbb70c825eee983d5",
"url": "https://llvm.googlesource.com/llvm-project/+/bccd34f32342e0f299d528ffbb70c825eee983d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c9900015a9a0bc2ccadae5e24b63ddbfe4d508fd"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c9900015a9a0bc2ccadae5e24b63ddbfe4d508fd",
"title": "c9900015a9a0bc2ccadae5e24b63ddbfe4d508fd",
"url": "https://llvm.googlesource.com/llvm-project/+/c9900015a9a0bc2ccadae5e24b63ddbfe4d508fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b7248b5cd464a1ce8f445c71f538c067dfaad9ac"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b7248b5cd464a1ce8f445c71f538c067dfaad9ac",
"title": "b7248b5cd464a1ce8f445c71f538c067dfaad9ac",
"url": "https://llvm.googlesource.com/llvm-project/+/b7248b5cd464a1ce8f445c71f538c067dfaad9ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5776915d0f83642b7ce54ace0c320bed4770987c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5776915d0f83642b7ce54ace0c320bed4770987c",
"title": "5776915d0f83642b7ce54ace0c320bed4770987c",
"url": "https://llvm.googlesource.com/llvm-project/+/5776915d0f83642b7ce54ace0c320bed4770987c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4d0c25f4a637e49abff8cbaffe70cf179b955ea9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4d0c25f4a637e49abff8cbaffe70cf179b955ea9",
"title": "4d0c25f4a637e49abff8cbaffe70cf179b955ea9",
"url": "https://llvm.googlesource.com/llvm-project/+/4d0c25f4a637e49abff8cbaffe70cf179b955ea9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "68494ae072676726722d87af6d9fd9e7c8b808bb"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@68494ae072676726722d87af6d9fd9e7c8b808bb",
"title": "68494ae072676726722d87af6d9fd9e7c8b808bb",
"url": "https://llvm.googlesource.com/llvm-project/+/68494ae072676726722d87af6d9fd9e7c8b808bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7a6568dcd5d471d5f836e5a7c810aff658e1d605"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7a6568dcd5d471d5f836e5a7c810aff658e1d605",
"title": "7a6568dcd5d471d5f836e5a7c810aff658e1d605",
"url": "https://llvm.googlesource.com/llvm-project/+/7a6568dcd5d471d5f836e5a7c810aff658e1d605"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "fbc4255ffcb8412a098926c7fc1ede823d6fb0e5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@fbc4255ffcb8412a098926c7fc1ede823d6fb0e5",
"title": "fbc4255ffcb8412a098926c7fc1ede823d6fb0e5",
"url": "https://llvm.googlesource.com/llvm-project/+/fbc4255ffcb8412a098926c7fc1ede823d6fb0e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "337bc916435f40b0e6f32e197237f007a4c6ee57"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@337bc916435f40b0e6f32e197237f007a4c6ee57",
"title": "337bc916435f40b0e6f32e197237f007a4c6ee57",
"url": "https://llvm.googlesource.com/llvm-project/+/337bc916435f40b0e6f32e197237f007a4c6ee57"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f7a09222f42a3fbb44ecbf8b1dd52583fb60efa8"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f7a09222f42a3fbb44ecbf8b1dd52583fb60efa8",
"title": "f7a09222f42a3fbb44ecbf8b1dd52583fb60efa8",
"url": "https://llvm.googlesource.com/llvm-project/+/f7a09222f42a3fbb44ecbf8b1dd52583fb60efa8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f78691630ae38a2663e9349a181ad7765c7be769"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f78691630ae38a2663e9349a181ad7765c7be769",
"title": "f78691630ae38a2663e9349a181ad7765c7be769",
"url": "https://llvm.googlesource.com/llvm-project/+/f78691630ae38a2663e9349a181ad7765c7be769"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b",
"title": "4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b",
"url": "https://llvm.googlesource.com/llvm-project/+/4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8875275377328240336"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:07:25.381] Scheduled build:
{
"id": "8709845315407489617",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-09T06:07:25.032342370Z",
"updateTime": "2025-07-09T06:07:25.032342370Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b",
"ref": "refs/heads/main"
}
}
}
[06:07:25.381] Task URL: https://cr-buildbucket.appspot.com/build/8709845315407489617
[06:07:25.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:2:0) after 1m36s
[06:07:26.379] Received PubSub notification, asking Buildbucket for the build status
[06:07:26.414] Build status: SCHEDULED
[06:09:01.403] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:2:0)
[06:09:01.426] Build status: SCHEDULED
[06:09:01.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:4:0) after 9m40s
[06:18:41.449] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:4:0)
[06:18:41.483] Build status: SCHEDULED
[06:18:41.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:5:0) after 7m53s
[06:26:34.858] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:5:0)
[06:26:34.892] Build status: SCHEDULED
[06:26:34.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:6:0) after 5m4s
[06:31:38.908] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:6:0)
[06:31:38.927] Build status: SCHEDULED
[06:31:38.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:7:0) after 3m50s
[06:35:29.031] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:7:0)
[06:35:29.060] Build status: SCHEDULED
[06:35:29.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:8:0) after 3m37s
[06:39:06.584] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:8:0)
[06:39:06.712] Build status: SCHEDULED
[06:39:06.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:9:0) after 2m31s
[06:41:38.297] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:9:0)
[06:41:38.317] Build status: SCHEDULED
[06:41:38.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:10:0) after 9m4s
[06:44:37.632] Received PubSub notification, asking Buildbucket for the build status
[06:44:37.730] Build status: STARTED
[06:50:42.342] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:10:0)
[06:50:42.371] Build status: STARTED
[06:50:42.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:12:0) after 6m41s
[06:57:23.430] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:12:0)
[06:57:23.498] Build status: STARTED
[06:57:23.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:13:0) after 5m1s
[07:02:24.533] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:13:0)
[07:02:24.613] Build status: STARTED
[07:02:24.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:14:0) after 5m48s
[07:08:12.641] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:14:0)
[07:08:12.709] Build status: STARTED
[07:08:12.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:15:0) after 8m11s
[07:16:23.728] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:15:0)
[07:16:23.865] Build status: STARTED
[07:16:23.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:16:0) after 2m32s
[07:18:55.901] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:16:0)
[07:18:55.929] Build status: STARTED
[07:18:55.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:17:0) after 1m35s
[07:20:30.945] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:17:0)
[07:20:30.963] Build status: STARTED
[07:20:30.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:18:0) after 1m22s
[07:21:52.989] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:18:0)
[07:21:53.014] Build status: STARTED
[07:21:53.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:19:0) after 1m20s
[07:23:13.038] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:19:0)
[07:23:13.060] Build status: STARTED
[07:23:13.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:20:0) after 1m4s
[07:24:17.138] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:20:0)
[07:24:17.170] Build status: STARTED
[07:24:17.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:21:0) after 4m44s
[07:29:01.235] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:21:0)
[07:29:01.372] Build status: STARTED
[07:29:01.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:22:0) after 9m43s
[07:38:44.372] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:22:0)
[07:38:44.453] Build status: STARTED
[07:38:44.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:23:0) after 1m4s
[07:39:48.479] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:23:0)
[07:39:48.537] Build status: STARTED
[07:39:48.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:24:0) after 8m29s
[07:48:17.698] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:24:0)
[07:48:17.716] Build status: STARTED
[07:48:17.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:25:0) after 8m45s
[07:57:02.769] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:25:0)
[07:57:02.799] Build status: STARTED
[07:57:02.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:26:0) after 6m24s
[08:03:26.829] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:26:0)
[08:03:26.847] Build status: STARTED
[08:03:26.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:27:0) after 5m14s
[08:08:40.892] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:27:0)
[08:08:40.920] Build status: STARTED
[08:08:40.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875275377328240336:28:0) after 3m40s
[08:12:15.109] Received PubSub notification, asking Buildbucket for the build status
[08:12:15.132] Build:
{
"id": "8709845315407489617",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-09T06:07:25.032342370Z",
"startTime": "2025-07-09T06:44:00.747203225Z",
"endTime": "2025-07-09T08:12:14.643790799Z",
"updateTime": "2025-07-09T08:12:14.643790799Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "4aa23ccd14d9425674bdb7b5aa5f97594d3dd96b",
"ref": "refs/heads/main"
}
}
}
[08:12:15.132] Invocation finished in 2h4m51.314770454s with status SUCCEEDED