[13:30:27.077] New invocation is queued and will start shortly
[13:30:28.694] Starting the invocation (attempt 1)
[13:30:28.738] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6"
[13:30:28.738] Popped gitiles commit info from properties and tags
[13:30:28.738] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:30:28.738] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:30:28.739] Buildbucket request:
{
"requestId": "8872167205916529488",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872167205916529488",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "33fe6353ef02c7d76c61eca7a136b7fb92b12450"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@33fe6353ef02c7d76c61eca7a136b7fb92b12450",
"title": "33fe6353ef02c7d76c61eca7a136b7fb92b12450",
"url": "https://llvm.googlesource.com/llvm-project/+/33fe6353ef02c7d76c61eca7a136b7fb92b12450"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1840106ddfb67f5fa1939f48e123fdd529eada50"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1840106ddfb67f5fa1939f48e123fdd529eada50",
"title": "1840106ddfb67f5fa1939f48e123fdd529eada50",
"url": "https://llvm.googlesource.com/llvm-project/+/1840106ddfb67f5fa1939f48e123fdd529eada50"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c3bf73bc4ade26b8b9dd5080ce7bccd88037cfd0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c3bf73bc4ade26b8b9dd5080ce7bccd88037cfd0",
"title": "c3bf73bc4ade26b8b9dd5080ce7bccd88037cfd0",
"url": "https://llvm.googlesource.com/llvm-project/+/c3bf73bc4ade26b8b9dd5080ce7bccd88037cfd0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "48da8489f2c1d416658527f6b806d618abd5ab73"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@48da8489f2c1d416658527f6b806d618abd5ab73",
"title": "48da8489f2c1d416658527f6b806d618abd5ab73",
"url": "https://llvm.googlesource.com/llvm-project/+/48da8489f2c1d416658527f6b806d618abd5ab73"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2a4971952520b49d4db2bb734214ae2fe2e5ee34"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2a4971952520b49d4db2bb734214ae2fe2e5ee34",
"title": "2a4971952520b49d4db2bb734214ae2fe2e5ee34",
"url": "https://llvm.googlesource.com/llvm-project/+/2a4971952520b49d4db2bb734214ae2fe2e5ee34"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ba5ff57917e099b2292cf66da960464570729c38"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ba5ff57917e099b2292cf66da960464570729c38",
"title": "ba5ff57917e099b2292cf66da960464570729c38",
"url": "https://llvm.googlesource.com/llvm-project/+/ba5ff57917e099b2292cf66da960464570729c38"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0f346a48a8f3f1e2241195611f9e32b19ac3de59"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0f346a48a8f3f1e2241195611f9e32b19ac3de59",
"title": "0f346a48a8f3f1e2241195611f9e32b19ac3de59",
"url": "https://llvm.googlesource.com/llvm-project/+/0f346a48a8f3f1e2241195611f9e32b19ac3de59"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5d099c2831f83bb8b8b2ae6621ef49282bc7283d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5d099c2831f83bb8b8b2ae6621ef49282bc7283d",
"title": "5d099c2831f83bb8b8b2ae6621ef49282bc7283d",
"url": "https://llvm.googlesource.com/llvm-project/+/5d099c2831f83bb8b8b2ae6621ef49282bc7283d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1d30f71b215b3adf6a51b7dc0b454dcadc4c4bc3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1d30f71b215b3adf6a51b7dc0b454dcadc4c4bc3",
"title": "1d30f71b215b3adf6a51b7dc0b454dcadc4c4bc3",
"url": "https://llvm.googlesource.com/llvm-project/+/1d30f71b215b3adf6a51b7dc0b454dcadc4c4bc3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f88be47fbf2b2c58863832b25c29032004d6da53"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f88be47fbf2b2c58863832b25c29032004d6da53",
"title": "f88be47fbf2b2c58863832b25c29032004d6da53",
"url": "https://llvm.googlesource.com/llvm-project/+/f88be47fbf2b2c58863832b25c29032004d6da53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b8104fa320f006bacd3e16afb431b5980dd5000a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b8104fa320f006bacd3e16afb431b5980dd5000a",
"title": "b8104fa320f006bacd3e16afb431b5980dd5000a",
"url": "https://llvm.googlesource.com/llvm-project/+/b8104fa320f006bacd3e16afb431b5980dd5000a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9115bef8ee1d9a47fef3221d9f3e0fd5c4ed947c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9115bef8ee1d9a47fef3221d9f3e0fd5c4ed947c",
"title": "9115bef8ee1d9a47fef3221d9f3e0fd5c4ed947c",
"url": "https://llvm.googlesource.com/llvm-project/+/9115bef8ee1d9a47fef3221d9f3e0fd5c4ed947c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9d96d01b42b9b11b375dca3927586f8962404e47"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9d96d01b42b9b11b375dca3927586f8962404e47",
"title": "9d96d01b42b9b11b375dca3927586f8962404e47",
"url": "https://llvm.googlesource.com/llvm-project/+/9d96d01b42b9b11b375dca3927586f8962404e47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "424258947e1153052f6187e64e099e6b21a14ff1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@424258947e1153052f6187e64e099e6b21a14ff1",
"title": "424258947e1153052f6187e64e099e6b21a14ff1",
"url": "https://llvm.googlesource.com/llvm-project/+/424258947e1153052f6187e64e099e6b21a14ff1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "475921d2dc3d30ca9cd00feebce5328abba779a0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@475921d2dc3d30ca9cd00feebce5328abba779a0",
"title": "475921d2dc3d30ca9cd00feebce5328abba779a0",
"url": "https://llvm.googlesource.com/llvm-project/+/475921d2dc3d30ca9cd00feebce5328abba779a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ef2b8805bf30ddb4720bb167ee7427070c8be7ce"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ef2b8805bf30ddb4720bb167ee7427070c8be7ce",
"title": "ef2b8805bf30ddb4720bb167ee7427070c8be7ce",
"url": "https://llvm.googlesource.com/llvm-project/+/ef2b8805bf30ddb4720bb167ee7427070c8be7ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c1f410779a934afd0fe558ef851c1d3fcd19e038"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c1f410779a934afd0fe558ef851c1d3fcd19e038",
"title": "c1f410779a934afd0fe558ef851c1d3fcd19e038",
"url": "https://llvm.googlesource.com/llvm-project/+/c1f410779a934afd0fe558ef851c1d3fcd19e038"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6",
"title": "ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6",
"url": "https://llvm.googlesource.com/llvm-project/+/ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872167205916529488"
},
{
"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"
}
}
[13:30:29.360] Scheduled build:
{
"id": "8706737143424672353",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-12T13:30:28.823446088Z",
"updateTime": "2025-08-12T13:30:28.823446088Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[13:30:29.360] Task URL: https://cr-buildbucket.appspot.com/build/8706737143424672353
[13:30:29.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:2:0) after 6m11s
[13:30:30.467] Received PubSub notification, asking Buildbucket for the build status
[13:30:30.503] Build status: SCHEDULED
[13:30:34.263] Received PubSub notification, asking Buildbucket for the build status
[13:30:34.281] Build status: STARTED
[13:36:40.381] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:2:0)
[13:36:40.397] Build status: STARTED
[13:36:40.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:5:0) after 8m45s
[13:45:25.424] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:5:0)
[13:45:25.440] Build status: STARTED
[13:45:25.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:6:0) after 9m59s
[13:55:24.479] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:6:0)
[13:55:24.595] Build status: STARTED
[13:55:24.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:7:0) after 6m43s
[14:02:07.625] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:7:0)
[14:02:07.643] Build status: STARTED
[14:02:07.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:8:0) after 6m0s
[14:08:07.698] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:8:0)
[14:08:07.717] Build status: STARTED
[14:08:07.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:9:0) after 1m3s
[14:09:10.740] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:9:0)
[14:09:10.763] Build status: STARTED
[14:09:10.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:10:0) after 1m6s
[14:10:16.784] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:10:0)
[14:10:16.828] Build status: STARTED
[14:10:16.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:11:0) after 6m54s
[14:17:10.931] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:11:0)
[14:17:10.957] Build status: STARTED
[14:17:10.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:12:0) after 1m33s
[14:18:43.940] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:12:0)
[14:18:43.958] Build status: STARTED
[14:18:43.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:13:0) after 9m20s
[14:28:03.983] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:13:0)
[14:28:04.008] Build status: STARTED
[14:28:04.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:14:0) after 3m19s
[14:31:23.086] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:14:0)
[14:31:23.114] Build status: STARTED
[14:31:23.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:15:0) after 4m43s
[14:36:06.133] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:15:0)
[14:36:06.443] Build status: STARTED
[14:36:06.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:16:0) after 8m3s
[14:44:09.422] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:16:0)
[14:44:09.477] Build status: STARTED
[14:44:09.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:17:0) after 9m18s
[14:53:27.517] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:17:0)
[14:53:27.563] Build status: STARTED
[14:53:27.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:18:0) after 5m21s
[14:58:48.591] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:18:0)
[14:58:48.641] Build status: STARTED
[14:58:48.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:19:0) after 2m39s
[15:01:27.689] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:19:0)
[15:01:27.706] Build status: STARTED
[15:01:27.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8872167205916529488:20:0) after 8m20s
[15:04:09.125] Received PubSub notification, asking Buildbucket for the build status
[15:04:09.149] Build:
{
"id": "8706737143424672353",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-12T13:30:28.823446088Z",
"startTime": "2025-08-12T13:30:34.083369938Z",
"endTime": "2025-08-12T15:04:08.728052772Z",
"updateTime": "2025-08-12T15:04:08.728052772Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ef5e65d27b940a2a2ce1d4ff20c38105d52d23b6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[15:04:09.149] Invocation finished in 1h33m42.085839296s with status SUCCEEDED