[21:40:58.469] New invocation is queued and will start shortly
[21:40:59.549] Starting the invocation (attempt 1)
[21:40:59.580] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/22625538cb13df55cc2ff71831f55b8042f9a26c"
[21:40:59.580] Popped gitiles commit info from properties and tags
[21:40:59.580] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:40:59.580] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:40:59.581] Buildbucket request:
{
"requestId": "8868965451042297936",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-host-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868965451042297936",
"job": "fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4a094095a42d43ba8f7326efa3e9fdc9f03bc467"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4a094095a42d43ba8f7326efa3e9fdc9f03bc467",
"title": "4a094095a42d43ba8f7326efa3e9fdc9f03bc467",
"url": "https://llvm.googlesource.com/llvm-project/+/4a094095a42d43ba8f7326efa3e9fdc9f03bc467"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f562e2a6f5b362f0deabd0234dfdeb702c9b2d5e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f562e2a6f5b362f0deabd0234dfdeb702c9b2d5e",
"title": "f562e2a6f5b362f0deabd0234dfdeb702c9b2d5e",
"url": "https://llvm.googlesource.com/llvm-project/+/f562e2a6f5b362f0deabd0234dfdeb702c9b2d5e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ec7a5b4028d192bf1f674554ffd2cfab5ae31412"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ec7a5b4028d192bf1f674554ffd2cfab5ae31412",
"title": "ec7a5b4028d192bf1f674554ffd2cfab5ae31412",
"url": "https://llvm.googlesource.com/llvm-project/+/ec7a5b4028d192bf1f674554ffd2cfab5ae31412"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a3762fb2405475c46c18a647f18d342f5e2332e3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a3762fb2405475c46c18a647f18d342f5e2332e3",
"title": "a3762fb2405475c46c18a647f18d342f5e2332e3",
"url": "https://llvm.googlesource.com/llvm-project/+/a3762fb2405475c46c18a647f18d342f5e2332e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0021a6b78cac45604416aef7c28436e83c9ce09e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0021a6b78cac45604416aef7c28436e83c9ce09e",
"title": "0021a6b78cac45604416aef7c28436e83c9ce09e",
"url": "https://llvm.googlesource.com/llvm-project/+/0021a6b78cac45604416aef7c28436e83c9ce09e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0ed9a4fb86aeaf24b8a8290d00432095ce0b5d64"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0ed9a4fb86aeaf24b8a8290d00432095ce0b5d64",
"title": "0ed9a4fb86aeaf24b8a8290d00432095ce0b5d64",
"url": "https://llvm.googlesource.com/llvm-project/+/0ed9a4fb86aeaf24b8a8290d00432095ce0b5d64"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "973f26a2b42c8a81471f5e3da3549f1db3b1ffb2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@973f26a2b42c8a81471f5e3da3549f1db3b1ffb2",
"title": "973f26a2b42c8a81471f5e3da3549f1db3b1ffb2",
"url": "https://llvm.googlesource.com/llvm-project/+/973f26a2b42c8a81471f5e3da3549f1db3b1ffb2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "22625538cb13df55cc2ff71831f55b8042f9a26c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@22625538cb13df55cc2ff71831f55b8042f9a26c",
"title": "22625538cb13df55cc2ff71831f55b8042f9a26c",
"url": "https://llvm.googlesource.com/llvm-project/+/22625538cb13df55cc2ff71831f55b8042f9a26c"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "22625538cb13df55cc2ff71831f55b8042f9a26c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868965451042297936"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:41:00.099] Scheduled build:
{
"id": "8703535389146317409",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-host-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-16T21:40:59.646255273Z",
"updateTime": "2025-09-16T21:40:59.646255273Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "22625538cb13df55cc2ff71831f55b8042f9a26c",
"ref": "refs/heads/main"
}
}
}
[21:41:00.099] Task URL: https://cr-buildbucket.appspot.com/build/8703535389146317409
[21:41:00.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:2:0) after 8m55s
[21:41:33.944] Received PubSub notification, asking Buildbucket for the build status
[21:41:33.959] Build status: SCHEDULED
[21:49:55.175] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:2:0)
[21:49:55.198] Build status: SCHEDULED
[21:49:55.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:4:0) after 5m46s
[21:55:41.373] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:4:0)
[21:55:41.392] Build status: SCHEDULED
[21:55:41.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:5:0) after 1m8s
[21:56:49.512] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:5:0)
[21:56:49.530] Build status: SCHEDULED
[21:56:49.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:6:0) after 5m32s
[22:02:21.590] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:6:0)
[22:02:21.604] Build status: SCHEDULED
[22:02:21.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:7:0) after 3m48s
[22:02:44.717] Received PubSub notification, asking Buildbucket for the build status
[22:02:44.731] Build status: STARTED
[22:06:09.726] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:7:0)
[22:06:09.746] Build status: STARTED
[22:06:09.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:9:0) after 1m32s
[22:07:41.766] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:9:0)
[22:07:41.795] Build status: STARTED
[22:07:41.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:10:0) after 5m53s
[22:13:34.810] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:10:0)
[22:13:34.835] Build status: STARTED
[22:13:34.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:11:0) after 8m15s
[22:21:49.852] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:11:0)
[22:21:49.874] Build status: STARTED
[22:21:49.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:12:0) after 8m53s
[22:30:42.866] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:12:0)
[22:30:42.884] Build status: STARTED
[22:30:42.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:13:0) after 5m33s
[22:36:15.901] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:13:0)
[22:36:15.953] Build status: STARTED
[22:36:15.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:14:0) after 2m14s
[22:38:29.970] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:14:0)
[22:38:29.989] Build status: STARTED
[22:38:29.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:15:0) after 5m43s
[22:44:13.081] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:15:0)
[22:44:13.110] Build status: STARTED
[22:44:13.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8868965451042297936:16:0) after 6m19s
[22:49:41.785] Received PubSub notification, asking Buildbucket for the build status
[22:49:41.810] Build:
{
"id": "8703535389146317409",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-host-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-16T21:40:59.646255273Z",
"startTime": "2025-09-16T22:02:44.536833800Z",
"endTime": "2025-09-16T22:49:41.457474895Z",
"updateTime": "2025-09-16T22:49:41.457474895Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "22625538cb13df55cc2ff71831f55b8042f9a26c",
"ref": "refs/heads/main"
}
}
}
[22:49:41.810] Invocation finished in 1h8m43.35589546s with status SUCCEEDED