[21:39:32.270] New invocation is queued and will start shortly
[21:39:33.417] Starting the invocation (attempt 1)
[21:39:33.444] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/cb6d1bbfcd01866ef7a3027968e6480ef1c2c992"
[21:39:33.444] Popped gitiles commit info from properties and tags
[21:39:33.444] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:39:33.445] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:39:33.445] Buildbucket request:
{
"requestId": "8874582553346590576",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8874582553346590576",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "94382c8e56e878d1b6a8cf317e3632df5352e23e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@94382c8e56e878d1b6a8cf317e3632df5352e23e",
"title": "94382c8e56e878d1b6a8cf317e3632df5352e23e",
"url": "https://llvm.googlesource.com/llvm-project/+/94382c8e56e878d1b6a8cf317e3632df5352e23e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ececa877083fcbe19aa0394b280630b9d807cd6d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ececa877083fcbe19aa0394b280630b9d807cd6d",
"title": "ececa877083fcbe19aa0394b280630b9d807cd6d",
"url": "https://llvm.googlesource.com/llvm-project/+/ececa877083fcbe19aa0394b280630b9d807cd6d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6824bcfdb4c8315a990f4b5ce2cb9f528281a823"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6824bcfdb4c8315a990f4b5ce2cb9f528281a823",
"title": "6824bcfdb4c8315a990f4b5ce2cb9f528281a823",
"url": "https://llvm.googlesource.com/llvm-project/+/6824bcfdb4c8315a990f4b5ce2cb9f528281a823"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8c28f4920dfda2e3d91c58e8eb5b568dd396fa2d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8c28f4920dfda2e3d91c58e8eb5b568dd396fa2d",
"title": "8c28f4920dfda2e3d91c58e8eb5b568dd396fa2d",
"url": "https://llvm.googlesource.com/llvm-project/+/8c28f4920dfda2e3d91c58e8eb5b568dd396fa2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b9f5b39e04d22e9a6ad451bdc0779bed31015372"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b9f5b39e04d22e9a6ad451bdc0779bed31015372",
"title": "b9f5b39e04d22e9a6ad451bdc0779bed31015372",
"url": "https://llvm.googlesource.com/llvm-project/+/b9f5b39e04d22e9a6ad451bdc0779bed31015372"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7caa0c9a55b33d8d627975e94c3367aa68dc37c7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7caa0c9a55b33d8d627975e94c3367aa68dc37c7",
"title": "7caa0c9a55b33d8d627975e94c3367aa68dc37c7",
"url": "https://llvm.googlesource.com/llvm-project/+/7caa0c9a55b33d8d627975e94c3367aa68dc37c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1e4e2b332dc08e01498c677a6a375fcbc9d5e9f0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1e4e2b332dc08e01498c677a6a375fcbc9d5e9f0",
"title": "1e4e2b332dc08e01498c677a6a375fcbc9d5e9f0",
"url": "https://llvm.googlesource.com/llvm-project/+/1e4e2b332dc08e01498c677a6a375fcbc9d5e9f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b7f6abdd052412bebfedc9cac26fc58b9edb618d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b7f6abdd052412bebfedc9cac26fc58b9edb618d",
"title": "b7f6abdd052412bebfedc9cac26fc58b9edb618d",
"url": "https://llvm.googlesource.com/llvm-project/+/b7f6abdd052412bebfedc9cac26fc58b9edb618d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "cb6d1bbfcd01866ef7a3027968e6480ef1c2c992"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@cb6d1bbfcd01866ef7a3027968e6480ef1c2c992",
"title": "cb6d1bbfcd01866ef7a3027968e6480ef1c2c992",
"url": "https://llvm.googlesource.com/llvm-project/+/cb6d1bbfcd01866ef7a3027968e6480ef1c2c992"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "cb6d1bbfcd01866ef7a3027968e6480ef1c2c992",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874582553346590576"
},
{
"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"
}
}
[21:39:33.865] Scheduled build:
{
"id": "8709152491376264193",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-16T21:39:33.516262037Z",
"updateTime": "2025-07-16T21:39:33.516262037Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "cb6d1bbfcd01866ef7a3027968e6480ef1c2c992",
"ref": "refs/heads/main"
}
}
}
[21:39:33.865] Task URL: https://cr-buildbucket.appspot.com/build/8709152491376264193
[21:39:33.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:2:0) after 7m13s
[21:39:34.858] Received PubSub notification, asking Buildbucket for the build status
[21:39:34.880] Build status: SCHEDULED
[21:46:46.879] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:2:0)
[21:46:46.906] Build status: SCHEDULED
[21:46:46.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:4:0) after 7m27s
[21:47:57.657] Received PubSub notification, asking Buildbucket for the build status
[21:47:57.686] Build status: STARTED
[21:54:13.930] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:4:0)
[21:54:13.960] Build status: STARTED
[21:54:13.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:6:0) after 5m6s
[21:59:20.193] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:6:0)
[21:59:20.219] Build status: STARTED
[21:59:20.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:7:0) after 4m22s
[22:03:42.260] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:7:0)
[22:03:42.386] Build status: STARTED
[22:03:42.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:8:0) after 8m44s
[22:12:26.476] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:8:0)
[22:12:26.504] Build status: STARTED
[22:12:26.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:9:0) after 9m54s
[22:22:20.501] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:9:0)
[22:22:20.522] Build status: STARTED
[22:22:20.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:10:0) after 1m29s
[22:23:49.551] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:10:0)
[22:23:49.580] Build status: STARTED
[22:23:49.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:11:0) after 2m39s
[22:26:28.602] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:11:0)
[22:26:28.619] Build status: STARTED
[22:26:28.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:12:0) after 1m24s
[22:27:52.659] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:12:0)
[22:27:52.683] Build status: STARTED
[22:27:52.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:13:0) after 4m9s
[22:32:01.830] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:13:0)
[22:32:01.872] Build status: STARTED
[22:32:01.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:14:0) after 7m40s
[22:39:41.966] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:14:0)
[22:39:41.997] Build status: STARTED
[22:39:41.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:15:0) after 4m43s
[22:44:25.018] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:15:0)
[22:44:25.036] Build status: STARTED
[22:44:25.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:16:0) after 9m11s
[22:53:36.082] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:16:0)
[22:53:36.109] Build status: STARTED
[22:53:36.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:17:0) after 9m53s
[23:03:29.127] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:17:0)
[23:03:29.144] Build status: STARTED
[23:03:29.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:18:0) after 7m3s
[23:10:32.164] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:18:0)
[23:10:32.184] Build status: STARTED
[23:10:32.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:19:0) after 4m8s
[23:14:40.268] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:19:0)
[23:14:40.289] Build status: STARTED
[23:14:40.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:20:0) after 2m28s
[23:17:08.318] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:20:0)
[23:17:08.339] Build status: STARTED
[23:17:08.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8874582553346590576:21:0) after 1m40s
[23:18:17.255] Received PubSub notification, asking Buildbucket for the build status
[23:18:17.274] Build:
{
"id": "8709152491376264193",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-16T21:39:33.516262037Z",
"startTime": "2025-07-16T21:47:57.452662257Z",
"endTime": "2025-07-16T23:18:16.847554641Z",
"updateTime": "2025-07-16T23:18:16.847554641Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "cb6d1bbfcd01866ef7a3027968e6480ef1c2c992",
"ref": "refs/heads/main"
}
}
}
[23:18:17.274] Invocation finished in 1h38m45.020381523s with status SUCCEEDED