[22:31:02.096] New invocation is queued and will start shortly
[22:31:03.189] Starting the invocation (attempt 1)
[22:31:03.210] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/abb8c4ba60f6512b63eba1cfc5723248a2e8d635"
[22:31:03.210] Popped gitiles commit info from properties and tags
[22:31:03.210] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:31:03.210] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:31:03.211] Buildbucket request:
{
"requestId": "8863888871384684816",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8863888871384684816",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "810d99335baf80c668c528d5abe9169da3214651"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@810d99335baf80c668c528d5abe9169da3214651",
"title": "810d99335baf80c668c528d5abe9169da3214651",
"url": "https://llvm.googlesource.com/llvm-project/+/810d99335baf80c668c528d5abe9169da3214651"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c41ef17653d7e2eea2435abdf3e963406d270c85"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c41ef17653d7e2eea2435abdf3e963406d270c85",
"title": "c41ef17653d7e2eea2435abdf3e963406d270c85",
"url": "https://llvm.googlesource.com/llvm-project/+/c41ef17653d7e2eea2435abdf3e963406d270c85"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2f7a5f7ffdff0660d465bf6024177529bc857de7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2f7a5f7ffdff0660d465bf6024177529bc857de7",
"title": "2f7a5f7ffdff0660d465bf6024177529bc857de7",
"url": "https://llvm.googlesource.com/llvm-project/+/2f7a5f7ffdff0660d465bf6024177529bc857de7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2d8563f4b880184c001da00decd92f2b0d6befe6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2d8563f4b880184c001da00decd92f2b0d6befe6",
"title": "2d8563f4b880184c001da00decd92f2b0d6befe6",
"url": "https://llvm.googlesource.com/llvm-project/+/2d8563f4b880184c001da00decd92f2b0d6befe6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d6c750b36ac73029bce9f1de6c976eb787c55253"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d6c750b36ac73029bce9f1de6c976eb787c55253",
"title": "d6c750b36ac73029bce9f1de6c976eb787c55253",
"url": "https://llvm.googlesource.com/llvm-project/+/d6c750b36ac73029bce9f1de6c976eb787c55253"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bbde792786dc93fc07cf245dd118f9d8b018de11"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bbde792786dc93fc07cf245dd118f9d8b018de11",
"title": "bbde792786dc93fc07cf245dd118f9d8b018de11",
"url": "https://llvm.googlesource.com/llvm-project/+/bbde792786dc93fc07cf245dd118f9d8b018de11"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5c3323a59fd2215f5465b28825cec9f6c417f029"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5c3323a59fd2215f5465b28825cec9f6c417f029",
"title": "5c3323a59fd2215f5465b28825cec9f6c417f029",
"url": "https://llvm.googlesource.com/llvm-project/+/5c3323a59fd2215f5465b28825cec9f6c417f029"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "47a3ea43f522ba6d9f7732b71de5df8bd8c1db48"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@47a3ea43f522ba6d9f7732b71de5df8bd8c1db48",
"title": "47a3ea43f522ba6d9f7732b71de5df8bd8c1db48",
"url": "https://llvm.googlesource.com/llvm-project/+/47a3ea43f522ba6d9f7732b71de5df8bd8c1db48"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "519cf3c2b8f25768916d97650f148a66db0bba6f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@519cf3c2b8f25768916d97650f148a66db0bba6f",
"title": "519cf3c2b8f25768916d97650f148a66db0bba6f",
"url": "https://llvm.googlesource.com/llvm-project/+/519cf3c2b8f25768916d97650f148a66db0bba6f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "10679307189fb5b51980acd33ae14d70345c6c75"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@10679307189fb5b51980acd33ae14d70345c6c75",
"title": "10679307189fb5b51980acd33ae14d70345c6c75",
"url": "https://llvm.googlesource.com/llvm-project/+/10679307189fb5b51980acd33ae14d70345c6c75"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2308d16fdc602502dbd22f162b79dbb62063e8a2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2308d16fdc602502dbd22f162b79dbb62063e8a2",
"title": "2308d16fdc602502dbd22f162b79dbb62063e8a2",
"url": "https://llvm.googlesource.com/llvm-project/+/2308d16fdc602502dbd22f162b79dbb62063e8a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dd1564e0e7bde9eda72320b6b839a16c3bcee642"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dd1564e0e7bde9eda72320b6b839a16c3bcee642",
"title": "dd1564e0e7bde9eda72320b6b839a16c3bcee642",
"url": "https://llvm.googlesource.com/llvm-project/+/dd1564e0e7bde9eda72320b6b839a16c3bcee642"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "abb8c4ba60f6512b63eba1cfc5723248a2e8d635"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@abb8c4ba60f6512b63eba1cfc5723248a2e8d635",
"title": "abb8c4ba60f6512b63eba1cfc5723248a2e8d635",
"url": "https://llvm.googlesource.com/llvm-project/+/abb8c4ba60f6512b63eba1cfc5723248a2e8d635"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "abb8c4ba60f6512b63eba1cfc5723248a2e8d635",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8863888871384684816"
},
{
"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"
}
}
[22:31:03.652] Scheduled build:
{
"id": "8698458809457841009",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-11T22:31:03.310709240Z",
"updateTime": "2025-11-11T22:31:03.310709240Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "abb8c4ba60f6512b63eba1cfc5723248a2e8d635",
"ref": "refs/heads/main"
}
}
}
[22:31:03.652] Task URL: https://cr-buildbucket.appspot.com/build/8698458809457841009
[22:31:03.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:2:0) after 2m44s
[22:31:04.561] Received PubSub notification, asking Buildbucket for the build status
[22:31:04.603] Build status: SCHEDULED
[22:31:07.770] Received PubSub notification, asking Buildbucket for the build status
[22:31:07.783] Build status: STARTED
[22:33:47.670] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:2:0)
[22:33:47.788] Build status: STARTED
[22:33:47.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:5:0) after 3m27s
[22:37:14.833] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:5:0)
[22:37:14.853] Build status: STARTED
[22:37:14.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:6:0) after 2m53s
[22:40:07.867] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:6:0)
[22:40:07.901] Build status: STARTED
[22:40:07.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:7:0) after 4m17s
[22:44:24.947] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:7:0)
[22:44:24.963] Build status: STARTED
[22:44:24.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:8:0) after 2m18s
[22:46:42.977] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:8:0)
[22:46:42.993] Build status: STARTED
[22:46:42.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:9:0) after 5m9s
[22:51:51.980] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:9:0)
[22:51:51.997] Build status: STARTED
[22:51:51.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:10:0) after 9m54s
[23:01:45.973] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:10:0)
[23:01:46.021] Build status: STARTED
[23:01:46.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:11:0) after 1m36s
[23:03:22.040] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:11:0)
[23:03:22.057] Build status: STARTED
[23:03:22.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:12:0) after 5m4s
[23:08:26.213] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:12:0)
[23:08:26.236] Build status: STARTED
[23:08:26.236] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:13:0) after 8m12s
[23:16:38.329] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:13:0)
[23:16:38.348] Build status: STARTED
[23:16:38.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:14:0) after 7m31s
[23:24:09.364] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:14:0)
[23:24:09.387] Build status: STARTED
[23:24:09.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:15:0) after 1m42s
[23:25:51.446] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:15:0)
[23:25:51.491] Build status: STARTED
[23:25:51.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:16:0) after 7m50s
[23:33:41.512] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:16:0)
[23:33:41.540] Build status: STARTED
[23:33:41.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:17:0) after 6m57s
[23:40:38.554] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:17:0)
[23:40:38.569] Build status: STARTED
[23:40:38.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:18:0) after 2m58s
[23:43:36.594] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:18:0)
[23:43:36.625] Build status: STARTED
[23:43:36.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:19:0) after 7m21s
[23:50:57.642] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:19:0)
[23:50:57.657] Build status: STARTED
[23:50:57.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:20:0) after 4m40s
[23:55:37.735] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:20:0)
[23:55:37.774] Build status: STARTED
[23:55:37.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:21:0) after 4m33s
[00:00:10.794] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:21:0)
[00:00:10.830] Build status: STARTED
[00:00:10.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8863888871384684816:22:0) after 4m27s
[00:04:23.801] Received PubSub notification, asking Buildbucket for the build status
[00:04:23.822] Build:
{
"id": "8698458809457841009",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-11T22:31:03.310709240Z",
"startTime": "2025-11-11T22:31:07.607351350Z",
"endTime": "2025-11-12T00:04:23.250984356Z",
"updateTime": "2025-11-12T00:04:23.250984356Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "abb8c4ba60f6512b63eba1cfc5723248a2e8d635",
"ref": "refs/heads/main"
}
}
}
[00:04:23.822] Invocation finished in 1h33m21.733475504s with status SUCCEEDED