[20:34:52.321] New invocation is queued and will start shortly
[20:34:53.591] Starting the invocation (attempt 1)
[20:34:53.618] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/8671429151d5e67d3f21a737809953ae8bdfbfde"
[20:34:53.618] Popped gitiles commit info from properties and tags
[20:34:53.618] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:34:53.619] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:34:53.619] Buildbucket request:
{
"requestId": "8916623614171123632",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8916623614171123632",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "89eb1a5a8e35a3bb77e4a1ca4fcac6757efc9339"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@89eb1a5a8e35a3bb77e4a1ca4fcac6757efc9339",
"title": "89eb1a5a8e35a3bb77e4a1ca4fcac6757efc9339",
"url": "https://llvm.googlesource.com/llvm-project/+/89eb1a5a8e35a3bb77e4a1ca4fcac6757efc9339"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "708c8cd7435002027a2cc9b99a0916a3dc255d63"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@708c8cd7435002027a2cc9b99a0916a3dc255d63",
"title": "708c8cd7435002027a2cc9b99a0916a3dc255d63",
"url": "https://llvm.googlesource.com/llvm-project/+/708c8cd7435002027a2cc9b99a0916a3dc255d63"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "aa6a089c367e024921bae050b8999a7bc4d58f76"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@aa6a089c367e024921bae050b8999a7bc4d58f76",
"title": "aa6a089c367e024921bae050b8999a7bc4d58f76",
"url": "https://llvm.googlesource.com/llvm-project/+/aa6a089c367e024921bae050b8999a7bc4d58f76"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "01d9528ef989610e968386ea1f270698015d2410"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@01d9528ef989610e968386ea1f270698015d2410",
"title": "01d9528ef989610e968386ea1f270698015d2410",
"url": "https://llvm.googlesource.com/llvm-project/+/01d9528ef989610e968386ea1f270698015d2410"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c23135c5488fbfaf6439433a10b3ddef33ff112c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c23135c5488fbfaf6439433a10b3ddef33ff112c",
"title": "c23135c5488fbfaf6439433a10b3ddef33ff112c",
"url": "https://llvm.googlesource.com/llvm-project/+/c23135c5488fbfaf6439433a10b3ddef33ff112c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3b43ae9a68256a77e8879a32a1670fd4b327802f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3b43ae9a68256a77e8879a32a1670fd4b327802f",
"title": "3b43ae9a68256a77e8879a32a1670fd4b327802f",
"url": "https://llvm.googlesource.com/llvm-project/+/3b43ae9a68256a77e8879a32a1670fd4b327802f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e276dcec173bc0123444c162c3becb2354382248"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e276dcec173bc0123444c162c3becb2354382248",
"title": "e276dcec173bc0123444c162c3becb2354382248",
"url": "https://llvm.googlesource.com/llvm-project/+/e276dcec173bc0123444c162c3becb2354382248"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8671429151d5e67d3f21a737809953ae8bdfbfde"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8671429151d5e67d3f21a737809953ae8bdfbfde",
"title": "8671429151d5e67d3f21a737809953ae8bdfbfde",
"url": "https://llvm.googlesource.com/llvm-project/+/8671429151d5e67d3f21a737809953ae8bdfbfde"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8671429151d5e67d3f21a737809953ae8bdfbfde",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916623614171123632"
},
{
"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"
}
}
[20:34:54.120] Scheduled build:
{
"id": "8751193552091075793",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-08T20:34:53.678396469Z",
"updateTime": "2024-04-08T20:34:53.678396469Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8671429151d5e67d3f21a737809953ae8bdfbfde",
"ref": "refs/heads/main"
}
}
}
[20:34:54.120] Task URL: https://cr-buildbucket.appspot.com/build/8751193552091075793
[20:34:54.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:2:0) after 5m45s
[20:34:55.030] Received PubSub notification, asking Buildbucket for the build status
[20:34:55.048] Build status: SCHEDULED
[20:34:58.590] Received PubSub notification, asking Buildbucket for the build status
[20:34:58.611] Build status: STARTED
[20:40:39.139] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:2:0)
[20:40:39.182] Build status: STARTED
[20:40:39.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:5:0) after 2m5s
[20:42:44.222] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:5:0)
[20:42:44.259] Build status: STARTED
[20:42:44.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:6:0) after 5m42s
[20:48:26.280] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:6:0)
[20:48:26.300] Build status: STARTED
[20:48:26.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:7:0) after 7m14s
[20:55:40.525] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:7:0)
[20:55:40.548] Build status: STARTED
[20:55:40.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:8:0) after 4m22s
[21:00:02.717] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:8:0)
[21:00:02.763] Build status: STARTED
[21:00:02.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:9:0) after 8m5s
[21:08:07.792] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:9:0)
[21:08:07.816] Build status: STARTED
[21:08:07.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:10:0) after 1m33s
[21:09:40.844] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:10:0)
[21:09:40.863] Build status: STARTED
[21:09:40.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:11:0) after 2m15s
[21:11:55.890] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:11:0)
[21:11:55.972] Build status: STARTED
[21:11:55.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:12:0) after 6m44s
[21:18:39.946] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:12:0)
[21:18:39.977] Build status: STARTED
[21:18:39.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:13:0) after 4m31s
[21:23:11.020] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:13:0)
[21:23:11.041] Build status: STARTED
[21:23:11.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:14:0) after 5m51s
[21:29:02.014] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:14:0)
[21:29:02.035] Build status: STARTED
[21:29:02.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:15:0) after 9m3s
[21:38:05.055] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:15:0)
[21:38:05.081] Build status: STARTED
[21:38:05.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:16:0) after 7m21s
[21:45:26.097] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:16:0)
[21:45:26.117] Build status: STARTED
[21:45:26.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8916623614171123632:17:0) after 5m38s
[21:49:08.113] Received PubSub notification, asking Buildbucket for the build status
[21:49:08.135] Build:
{
"id": "8751193552091075793",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-08T20:34:53.678396469Z",
"startTime": "2024-04-08T20:34:58.232981457Z",
"endTime": "2024-04-08T21:49:07.754471880Z",
"updateTime": "2024-04-08T21:49:07.754471880Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8671429151d5e67d3f21a737809953ae8bdfbfde",
"ref": "refs/heads/main"
}
}
}
[21:49:08.135] Invocation finished in 1h14m15.823235043s with status SUCCEEDED