[15:22:48.675] New invocation is queued and will start shortly
[15:22:51.728] Starting the invocation (attempt 1)
[15:22:51.754] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/2fb4bfa5ba972bf074bea8a89590171979055a41"
[15:22:51.754] Popped gitiles commit info from properties and tags
[15:22:51.754] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:22:51.754] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:22:51.754] Buildbucket request:
{
"requestId": "8938477116243312544",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8938477116243312544",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9a53f5f5c466b8d2a63827ec33dd97e4333db6ce"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9a53f5f5c466b8d2a63827ec33dd97e4333db6ce",
"title": "9a53f5f5c466b8d2a63827ec33dd97e4333db6ce",
"url": "https://llvm.googlesource.com/llvm-project/+/9a53f5f5c466b8d2a63827ec33dd97e4333db6ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1030483561d6abf0a160bca324509d0c7ef4e398"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1030483561d6abf0a160bca324509d0c7ef4e398",
"title": "1030483561d6abf0a160bca324509d0c7ef4e398",
"url": "https://llvm.googlesource.com/llvm-project/+/1030483561d6abf0a160bca324509d0c7ef4e398"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8f18cf77e79e1f1534d97220a14d684095f7b92a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8f18cf77e79e1f1534d97220a14d684095f7b92a",
"title": "8f18cf77e79e1f1534d97220a14d684095f7b92a",
"url": "https://llvm.googlesource.com/llvm-project/+/8f18cf77e79e1f1534d97220a14d684095f7b92a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b108c11e4656c695853a14032ba33671a676203e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b108c11e4656c695853a14032ba33671a676203e",
"title": "b108c11e4656c695853a14032ba33671a676203e",
"url": "https://llvm.googlesource.com/llvm-project/+/b108c11e4656c695853a14032ba33671a676203e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c52d9509d40d3048914b144618232213e6076e05"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c52d9509d40d3048914b144618232213e6076e05",
"title": "c52d9509d40d3048914b144618232213e6076e05",
"url": "https://llvm.googlesource.com/llvm-project/+/c52d9509d40d3048914b144618232213e6076e05"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ed72dc8c1fdae691f5d57bb2254e3922db46b7e2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ed72dc8c1fdae691f5d57bb2254e3922db46b7e2",
"title": "ed72dc8c1fdae691f5d57bb2254e3922db46b7e2",
"url": "https://llvm.googlesource.com/llvm-project/+/ed72dc8c1fdae691f5d57bb2254e3922db46b7e2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5820c9257e38fbfc15c314b50e629616800a0f95"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5820c9257e38fbfc15c314b50e629616800a0f95",
"title": "5820c9257e38fbfc15c314b50e629616800a0f95",
"url": "https://llvm.googlesource.com/llvm-project/+/5820c9257e38fbfc15c314b50e629616800a0f95"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2fb4bfa5ba972bf074bea8a89590171979055a41"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2fb4bfa5ba972bf074bea8a89590171979055a41",
"title": "2fb4bfa5ba972bf074bea8a89590171979055a41",
"url": "https://llvm.googlesource.com/llvm-project/+/2fb4bfa5ba972bf074bea8a89590171979055a41"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2fb4bfa5ba972bf074bea8a89590171979055a41",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938477116243312544"
},
{
"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"
}
}
[15:22:52.589] Scheduled build:
{
"id": "8773047052224429217",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-11T15:22:51.878757160Z",
"updateTime": "2023-08-11T15:22:51.878757160Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2fb4bfa5ba972bf074bea8a89590171979055a41",
"ref": "refs/heads/main"
}
}
}
[15:22:52.589] Task URL: https://cr-buildbucket.appspot.com/build/8773047052224429217
[15:22:52.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:2:0) after 8m41s
[15:22:53.632] Received PubSub notification, asking Buildbucket for the build status
[15:22:53.654] Build status: SCHEDULED
[15:24:59.587] Received PubSub notification, asking Buildbucket for the build status
[15:24:59.617] Build status: STARTED
[15:31:33.803] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:2:0)
[15:31:33.847] Build status: STARTED
[15:31:33.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:5:0) after 5m3s
[15:36:36.858] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:5:0)
[15:36:36.877] Build status: STARTED
[15:36:36.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:6:0) after 3m33s
[15:40:09.893] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:6:0)
[15:40:09.916] Build status: STARTED
[15:40:09.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:7:0) after 2m19s
[15:42:29.758] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:7:0)
[15:42:29.777] Build status: STARTED
[15:42:29.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:8:0) after 6m45s
[15:49:14.795] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:8:0)
[15:49:14.826] Build status: STARTED
[15:49:14.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:9:0) after 8m13s
[15:57:27.917] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:9:0)
[15:57:27.932] Build status: STARTED
[15:57:27.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:10:0) after 6m18s
[16:03:45.956] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:10:0)
[16:03:45.976] Build status: STARTED
[16:03:45.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:11:0) after 3m53s
[16:07:39.003] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:11:0)
[16:07:39.025] Build status: STARTED
[16:07:39.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:12:0) after 4m13s
[16:11:52.039] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:12:0)
[16:11:52.071] Build status: STARTED
[16:11:52.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:13:0) after 6m58s
[16:18:50.249] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:13:0)
[16:18:50.283] Build status: STARTED
[16:18:50.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:14:0) after 1m58s
[16:20:48.750] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:14:0)
[16:20:48.777] Build status: STARTED
[16:20:48.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8938477116243312544:15:0) after 9m52s
[16:27:32.558] Received PubSub notification, asking Buildbucket for the build status
[16:27:32.586] Build:
{
"id": "8773047052224429217",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-11T15:22:51.878757160Z",
"startTime": "2023-08-11T15:24:58.863422Z",
"endTime": "2023-08-11T16:27:32.075931617Z",
"updateTime": "2023-08-11T16:27:32.075931617Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2fb4bfa5ba972bf074bea8a89590171979055a41",
"ref": "refs/heads/main"
}
}
}
[16:27:32.586] Invocation finished in 1h4m43.925889888s with status SUCCEEDED