[01:17:05.521] New invocation is queued and will start shortly
[01:17:06.567] Starting the invocation (attempt 1)
[01:17:06.594] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f"
[01:17:06.594] Popped gitiles commit info from properties and tags
[01:17:06.594] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:17:06.594] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:17:06.595] Buildbucket request:
{
"requestId": "8883628562658190704",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883628562658190704",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ddb32a67c2e76bc0f69470a8d02a220e8083fa68"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ddb32a67c2e76bc0f69470a8d02a220e8083fa68",
"title": "ddb32a67c2e76bc0f69470a8d02a220e8083fa68",
"url": "https://llvm.googlesource.com/llvm-project/+/ddb32a67c2e76bc0f69470a8d02a220e8083fa68"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a38ad6e2a24b46eaea481c04de566dd2a45d667b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a38ad6e2a24b46eaea481c04de566dd2a45d667b",
"title": "a38ad6e2a24b46eaea481c04de566dd2a45d667b",
"url": "https://llvm.googlesource.com/llvm-project/+/a38ad6e2a24b46eaea481c04de566dd2a45d667b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "65813e0e94c0403dad61e8365b39d76d7b3bfc14"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@65813e0e94c0403dad61e8365b39d76d7b3bfc14",
"title": "65813e0e94c0403dad61e8365b39d76d7b3bfc14",
"url": "https://llvm.googlesource.com/llvm-project/+/65813e0e94c0403dad61e8365b39d76d7b3bfc14"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0afa872a0db41cba313df473aa3fea52a35c8e70"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0afa872a0db41cba313df473aa3fea52a35c8e70",
"title": "0afa872a0db41cba313df473aa3fea52a35c8e70",
"url": "https://llvm.googlesource.com/llvm-project/+/0afa872a0db41cba313df473aa3fea52a35c8e70"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "99d12ac172f34e4963cf3a36536719149d30d788"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@99d12ac172f34e4963cf3a36536719149d30d788",
"title": "99d12ac172f34e4963cf3a36536719149d30d788",
"url": "https://llvm.googlesource.com/llvm-project/+/99d12ac172f34e4963cf3a36536719149d30d788"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d7354e337a4602da1e5913b3e6fceda2e8c5ecc0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d7354e337a4602da1e5913b3e6fceda2e8c5ecc0",
"title": "d7354e337a4602da1e5913b3e6fceda2e8c5ecc0",
"url": "https://llvm.googlesource.com/llvm-project/+/d7354e337a4602da1e5913b3e6fceda2e8c5ecc0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1f359610b048ae31e5ea3a0416f55405ff398753"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1f359610b048ae31e5ea3a0416f55405ff398753",
"title": "1f359610b048ae31e5ea3a0416f55405ff398753",
"url": "https://llvm.googlesource.com/llvm-project/+/1f359610b048ae31e5ea3a0416f55405ff398753"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "735f5b1cb45c4245f1c068dda8271142457925ec"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@735f5b1cb45c4245f1c068dda8271142457925ec",
"title": "735f5b1cb45c4245f1c068dda8271142457925ec",
"url": "https://llvm.googlesource.com/llvm-project/+/735f5b1cb45c4245f1c068dda8271142457925ec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1356e202b2b2747220e12c2a8cbd28846bb61548"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1356e202b2b2747220e12c2a8cbd28846bb61548",
"title": "1356e202b2b2747220e12c2a8cbd28846bb61548",
"url": "https://llvm.googlesource.com/llvm-project/+/1356e202b2b2747220e12c2a8cbd28846bb61548"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a3754ade637f71d36495eb95ea2d8cab9885c9d9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a3754ade637f71d36495eb95ea2d8cab9885c9d9",
"title": "a3754ade637f71d36495eb95ea2d8cab9885c9d9",
"url": "https://llvm.googlesource.com/llvm-project/+/a3754ade637f71d36495eb95ea2d8cab9885c9d9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "707367621679742eae0e903470a5fe043424c698"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@707367621679742eae0e903470a5fe043424c698",
"title": "707367621679742eae0e903470a5fe043424c698",
"url": "https://llvm.googlesource.com/llvm-project/+/707367621679742eae0e903470a5fe043424c698"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "87afe4de22f24c81d6eca8c2ecdec5e57d6a961a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@87afe4de22f24c81d6eca8c2ecdec5e57d6a961a",
"title": "87afe4de22f24c81d6eca8c2ecdec5e57d6a961a",
"url": "https://llvm.googlesource.com/llvm-project/+/87afe4de22f24c81d6eca8c2ecdec5e57d6a961a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8286b804d519d045fa1ee3a1a2e7fae66e5e30ef"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8286b804d519d045fa1ee3a1a2e7fae66e5e30ef",
"title": "8286b804d519d045fa1ee3a1a2e7fae66e5e30ef",
"url": "https://llvm.googlesource.com/llvm-project/+/8286b804d519d045fa1ee3a1a2e7fae66e5e30ef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "76e219d9c3b46e0cc1813e1e8452da0f4f54ab92"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@76e219d9c3b46e0cc1813e1e8452da0f4f54ab92",
"title": "76e219d9c3b46e0cc1813e1e8452da0f4f54ab92",
"url": "https://llvm.googlesource.com/llvm-project/+/76e219d9c3b46e0cc1813e1e8452da0f4f54ab92"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1a9928442b7dbcd596d6b577809c22330706ccb5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1a9928442b7dbcd596d6b577809c22330706ccb5",
"title": "1a9928442b7dbcd596d6b577809c22330706ccb5",
"url": "https://llvm.googlesource.com/llvm-project/+/1a9928442b7dbcd596d6b577809c22330706ccb5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a168ddc470825091ad52da12042fb38491ed81d6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a168ddc470825091ad52da12042fb38491ed81d6",
"title": "a168ddc470825091ad52da12042fb38491ed81d6",
"url": "https://llvm.googlesource.com/llvm-project/+/a168ddc470825091ad52da12042fb38491ed81d6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "80fde75dc6c50a7d32f6dbfda9a6f2c24890b5cc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@80fde75dc6c50a7d32f6dbfda9a6f2c24890b5cc",
"title": "80fde75dc6c50a7d32f6dbfda9a6f2c24890b5cc",
"url": "https://llvm.googlesource.com/llvm-project/+/80fde75dc6c50a7d32f6dbfda9a6f2c24890b5cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "20eb60d3f2d416976b1b31384e97db21ee0f28c9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@20eb60d3f2d416976b1b31384e97db21ee0f28c9",
"title": "20eb60d3f2d416976b1b31384e97db21ee0f28c9",
"url": "https://llvm.googlesource.com/llvm-project/+/20eb60d3f2d416976b1b31384e97db21ee0f28c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f",
"title": "bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f",
"url": "https://llvm.googlesource.com/llvm-project/+/bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883628562658190704"
},
{
"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"
}
}
[01:17:06.939] Scheduled build:
{
"id": "8718198500814187329",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-08T01:17:06.650522943Z",
"updateTime": "2025-04-08T01:17:06.650522943Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f",
"ref": "refs/heads/main"
}
}
}
[01:17:06.939] Task URL: https://cr-buildbucket.appspot.com/build/8718198500814187329
[01:17:06.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:2:0) after 1m46s
[01:17:07.921] Received PubSub notification, asking Buildbucket for the build status
[01:17:07.944] Build status: SCHEDULED
[01:18:52.958] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:2:0)
[01:18:52.984] Build status: SCHEDULED
[01:18:52.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:4:0) after 3m2s
[01:21:55.115] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:4:0)
[01:21:55.173] Build status: SCHEDULED
[01:21:55.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:5:0) after 4m40s
[01:26:35.234] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:5:0)
[01:26:35.254] Build status: SCHEDULED
[01:26:35.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:6:0) after 6m14s
[01:32:49.325] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:6:0)
[01:32:49.352] Build status: SCHEDULED
[01:32:49.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:7:0) after 3m47s
[01:36:36.407] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:7:0)
[01:36:36.436] Build status: SCHEDULED
[01:36:36.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:8:0) after 5m11s
[01:41:47.467] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:8:0)
[01:41:47.485] Build status: SCHEDULED
[01:41:47.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:9:0) after 1m24s
[01:42:29.617] Received PubSub notification, asking Buildbucket for the build status
[01:42:29.637] Build status: STARTED
[01:43:11.514] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:9:0)
[01:43:11.561] Build status: STARTED
[01:43:11.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:11:0) after 4m40s
[01:47:51.627] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:11:0)
[01:47:51.654] Build status: STARTED
[01:47:51.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:12:0) after 7m44s
[01:55:35.648] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:12:0)
[01:55:35.742] Build status: STARTED
[01:55:35.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:13:0) after 4m31s
[02:00:06.783] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:13:0)
[02:00:06.807] Build status: STARTED
[02:00:06.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:14:0) after 6m38s
[02:06:44.828] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:14:0)
[02:06:44.853] Build status: STARTED
[02:06:44.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:15:0) after 8m24s
[02:15:08.865] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:15:0)
[02:15:08.892] Build status: STARTED
[02:15:08.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:16:0) after 9m52s
[02:25:00.976] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:16:0)
[02:25:01.034] Build status: STARTED
[02:25:01.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:17:0) after 1m51s
[02:26:52.155] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:17:0)
[02:26:52.169] Build status: STARTED
[02:26:52.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:18:0) after 5m16s
[02:32:08.181] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:18:0)
[02:32:08.209] Build status: STARTED
[02:32:08.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:19:0) after 5m14s
[02:37:22.240] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:19:0)
[02:37:22.254] Build status: STARTED
[02:37:22.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:20:0) after 8m0s
[02:45:22.536] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:20:0)
[02:45:22.552] Build status: STARTED
[02:45:22.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:21:0) after 5m25s
[02:50:47.603] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:21:0)
[02:50:47.619] Build status: STARTED
[02:50:47.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:22:0) after 9m45s
[03:00:32.658] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:22:0)
[03:00:32.672] Build status: STARTED
[03:00:32.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:23:0) after 2m20s
[03:02:52.709] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:23:0)
[03:02:52.727] Build status: STARTED
[03:02:52.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:24:0) after 2m23s
[03:05:15.802] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:24:0)
[03:05:15.821] Build status: STARTED
[03:05:15.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:25:0) after 4m38s
[03:09:53.840] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:25:0)
[03:09:53.863] Build status: STARTED
[03:09:53.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:26:0) after 2m26s
[03:12:19.890] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:26:0)
[03:12:19.906] Build status: STARTED
[03:12:19.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8883628562658190704:27:0) after 2m38s
[03:14:33.712] Received PubSub notification, asking Buildbucket for the build status
[03:14:33.728] Build:
{
"id": "8718198500814187329",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-08T01:17:06.650522943Z",
"startTime": "2025-04-08T01:42:07.048248365Z",
"endTime": "2025-04-08T03:14:33.205077130Z",
"updateTime": "2025-04-08T03:14:33.205077130Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "bb1f32ded0b7bec3b69c30b1c21eb1210372cd7f",
"ref": "refs/heads/main"
}
}
}
[03:14:33.728] Invocation finished in 1h57m28.22163272s with status SUCCEEDED