[17:28:04.186] New invocation is queued and will start shortly
[17:28:05.369] Starting the invocation (attempt 1)
[17:28:05.392] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/8a055f8067f4c3a121acaa25d4f20918e1dc0b81"
[17:28:05.392] Popped gitiles commit info from properties and tags
[17:28:05.392] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:28:05.393] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:28:05.393] Buildbucket request:
{
"requestId": "8863454947469386096",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8863454947469386096",
"job": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "47da0f1e91d06deae5d60229485f42242891a7f4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@47da0f1e91d06deae5d60229485f42242891a7f4",
"title": "47da0f1e91d06deae5d60229485f42242891a7f4",
"url": "https://llvm.googlesource.com/llvm-project/+/47da0f1e91d06deae5d60229485f42242891a7f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e8cc0c3eb82789c9d2a22e094a941be180e9ab4b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e8cc0c3eb82789c9d2a22e094a941be180e9ab4b",
"title": "e8cc0c3eb82789c9d2a22e094a941be180e9ab4b",
"url": "https://llvm.googlesource.com/llvm-project/+/e8cc0c3eb82789c9d2a22e094a941be180e9ab4b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2394eb118045bd47c1c75f9cab42d701221846a0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2394eb118045bd47c1c75f9cab42d701221846a0",
"title": "2394eb118045bd47c1c75f9cab42d701221846a0",
"url": "https://llvm.googlesource.com/llvm-project/+/2394eb118045bd47c1c75f9cab42d701221846a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "688b190745655c30a39a25f2e274fd62f0e9cce4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@688b190745655c30a39a25f2e274fd62f0e9cce4",
"title": "688b190745655c30a39a25f2e274fd62f0e9cce4",
"url": "https://llvm.googlesource.com/llvm-project/+/688b190745655c30a39a25f2e274fd62f0e9cce4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7262c592c124a5bd8a5b534af5ed0390511116d9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7262c592c124a5bd8a5b534af5ed0390511116d9",
"title": "7262c592c124a5bd8a5b534af5ed0390511116d9",
"url": "https://llvm.googlesource.com/llvm-project/+/7262c592c124a5bd8a5b534af5ed0390511116d9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ea0ecd63d4aa6ebe0fd11fe48bb707ba024d5a49"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ea0ecd63d4aa6ebe0fd11fe48bb707ba024d5a49",
"title": "ea0ecd63d4aa6ebe0fd11fe48bb707ba024d5a49",
"url": "https://llvm.googlesource.com/llvm-project/+/ea0ecd63d4aa6ebe0fd11fe48bb707ba024d5a49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e1e696d2eb0f4467133275ff6a2661f389dd3dbe"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e1e696d2eb0f4467133275ff6a2661f389dd3dbe",
"title": "e1e696d2eb0f4467133275ff6a2661f389dd3dbe",
"url": "https://llvm.googlesource.com/llvm-project/+/e1e696d2eb0f4467133275ff6a2661f389dd3dbe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "180b59c37ab2082a2e68a2b75283e7fa4527552a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@180b59c37ab2082a2e68a2b75283e7fa4527552a",
"title": "180b59c37ab2082a2e68a2b75283e7fa4527552a",
"url": "https://llvm.googlesource.com/llvm-project/+/180b59c37ab2082a2e68a2b75283e7fa4527552a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8a055f8067f4c3a121acaa25d4f20918e1dc0b81"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8a055f8067f4c3a121acaa25d4f20918e1dc0b81",
"title": "8a055f8067f4c3a121acaa25d4f20918e1dc0b81",
"url": "https://llvm.googlesource.com/llvm-project/+/8a055f8067f4c3a121acaa25d4f20918e1dc0b81"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8a055f8067f4c3a121acaa25d4f20918e1dc0b81",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8863454947469386096"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:28:05.858] Scheduled build:
{
"id": "8698024885456533425",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-16T17:28:05.485376197Z",
"updateTime": "2025-11-16T17:28:05.485376197Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8a055f8067f4c3a121acaa25d4f20918e1dc0b81",
"ref": "refs/heads/main"
}
}
}
[17:28:05.858] Task URL: https://cr-buildbucket.appspot.com/build/8698024885456533425
[17:28:05.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:2:0) after 7m14s
[17:28:06.854] Received PubSub notification, asking Buildbucket for the build status
[17:28:06.909] Build status: SCHEDULED
[17:28:09.083] Received PubSub notification, asking Buildbucket for the build status
[17:28:09.108] Build status: STARTED
[17:35:19.935] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:2:0)
[17:35:19.955] Build status: STARTED
[17:35:19.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:5:0) after 4m50s
[17:40:09.966] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:5:0)
[17:40:09.982] Build status: STARTED
[17:40:09.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:6:0) after 8m16s
[17:48:25.997] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:6:0)
[17:48:26.036] Build status: STARTED
[17:48:26.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:7:0) after 3m53s
[17:52:19.062] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:7:0)
[17:52:19.096] Build status: STARTED
[17:52:19.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:8:0) after 3m6s
[17:55:25.289] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:8:0)
[17:55:25.312] Build status: STARTED
[17:55:25.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:9:0) after 7m5s
[18:02:30.344] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:9:0)
[18:02:30.367] Build status: STARTED
[18:02:30.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:10:0) after 7m32s
[18:10:02.545] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:10:0)
[18:10:02.563] Build status: STARTED
[18:10:02.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:11:0) after 2m55s
[18:12:57.634] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:11:0)
[18:12:57.655] Build status: STARTED
[18:12:57.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:12:0) after 6m13s
[18:19:10.769] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:12:0)
[18:19:10.794] Build status: STARTED
[18:19:10.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:13:0) after 8m38s
[18:27:48.810] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:13:0)
[18:27:48.833] Build status: STARTED
[18:27:48.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:14:0) after 4m24s
[18:32:12.946] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:14:0)
[18:32:12.974] Build status: STARTED
[18:32:12.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:15:0) after 2m38s
[18:34:51.292] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:15:0)
[18:34:51.316] Build status: STARTED
[18:34:51.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:16:0) after 2m9s
[18:37:00.338] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:16:0)
[18:37:00.381] Build status: STARTED
[18:37:00.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:17:0) after 3m59s
[18:40:59.395] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:17:0)
[18:40:59.440] Build status: STARTED
[18:40:59.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:18:0) after 2m11s
[18:43:10.456] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:18:0)
[18:43:10.475] Build status: STARTED
[18:43:10.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:19:0) after 6m59s
[18:50:09.493] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:19:0)
[18:50:09.512] Build status: STARTED
[18:50:09.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:20:0) after 7m16s
[18:57:25.554] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:20:0)
[18:57:25.579] Build status: STARTED
[18:57:25.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8863454947469386096:21:0) after 2m39s
[18:57:59.462] Received PubSub notification, asking Buildbucket for the build status
[18:57:59.480] Build:
{
"id": "8698024885456533425",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-16T17:28:05.485376197Z",
"startTime": "2025-11-16T17:28:08.851573739Z",
"endTime": "2025-11-16T18:57:55.856870149Z",
"updateTime": "2025-11-16T18:57:55.856870149Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8a055f8067f4c3a121acaa25d4f20918e1dc0b81",
"ref": "refs/heads/main"
}
}
}
[18:57:59.480] Invocation finished in 1h29m55.298345944s with status SUCCEEDED