[02:21:09.476] New invocation is queued and will start shortly
[02:21:10.597] Starting the invocation (attempt 1)
[02:21:10.626] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/0b533f2d9f0551aaffb13dcac8e0fd0a952185b5"
[02:21:10.626] Popped gitiles commit info from properties and tags
[02:21:10.626] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:21:10.626] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:21:10.627] Buildbucket request:
{
"requestId": "8875380208036294336",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8875380208036294336",
"job": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "659c8102f40d4c9f73f777e70a0aa5bc8d99a35f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@659c8102f40d4c9f73f777e70a0aa5bc8d99a35f",
"title": "659c8102f40d4c9f73f777e70a0aa5bc8d99a35f",
"url": "https://llvm.googlesource.com/llvm-project/+/659c8102f40d4c9f73f777e70a0aa5bc8d99a35f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6efa366b43686bd8b1ec509e589752535507866e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6efa366b43686bd8b1ec509e589752535507866e",
"title": "6efa366b43686bd8b1ec509e589752535507866e",
"url": "https://llvm.googlesource.com/llvm-project/+/6efa366b43686bd8b1ec509e589752535507866e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2bd31edd572b5de459c6c0e86f8e2314beed751a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2bd31edd572b5de459c6c0e86f8e2314beed751a",
"title": "2bd31edd572b5de459c6c0e86f8e2314beed751a",
"url": "https://llvm.googlesource.com/llvm-project/+/2bd31edd572b5de459c6c0e86f8e2314beed751a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a5eae0f8f995bdcb1e7f0cacd3b6d50ec5319a77"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a5eae0f8f995bdcb1e7f0cacd3b6d50ec5319a77",
"title": "a5eae0f8f995bdcb1e7f0cacd3b6d50ec5319a77",
"url": "https://llvm.googlesource.com/llvm-project/+/a5eae0f8f995bdcb1e7f0cacd3b6d50ec5319a77"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3528e16ff8a6b44ce32992d1e423ed3637b1fdf4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3528e16ff8a6b44ce32992d1e423ed3637b1fdf4",
"title": "3528e16ff8a6b44ce32992d1e423ed3637b1fdf4",
"url": "https://llvm.googlesource.com/llvm-project/+/3528e16ff8a6b44ce32992d1e423ed3637b1fdf4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "fe56f69810b1909e0818a6446744f89533af7e38"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@fe56f69810b1909e0818a6446744f89533af7e38",
"title": "fe56f69810b1909e0818a6446744f89533af7e38",
"url": "https://llvm.googlesource.com/llvm-project/+/fe56f69810b1909e0818a6446744f89533af7e38"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5088231e52824c265d2d5b2ecb3c472283f2969b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5088231e52824c265d2d5b2ecb3c472283f2969b",
"title": "5088231e52824c265d2d5b2ecb3c472283f2969b",
"url": "https://llvm.googlesource.com/llvm-project/+/5088231e52824c265d2d5b2ecb3c472283f2969b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "591b0d0fdfa944358d0fdf4603d61cc8cfb1f300"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@591b0d0fdfa944358d0fdf4603d61cc8cfb1f300",
"title": "591b0d0fdfa944358d0fdf4603d61cc8cfb1f300",
"url": "https://llvm.googlesource.com/llvm-project/+/591b0d0fdfa944358d0fdf4603d61cc8cfb1f300"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a79acbd92febf266faf5ef6b53fb28770b778307"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a79acbd92febf266faf5ef6b53fb28770b778307",
"title": "a79acbd92febf266faf5ef6b53fb28770b778307",
"url": "https://llvm.googlesource.com/llvm-project/+/a79acbd92febf266faf5ef6b53fb28770b778307"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "884f7382934dba80bac13858940202be7b72e7c4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@884f7382934dba80bac13858940202be7b72e7c4",
"title": "884f7382934dba80bac13858940202be7b72e7c4",
"url": "https://llvm.googlesource.com/llvm-project/+/884f7382934dba80bac13858940202be7b72e7c4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "181b014c06d9130455f95dcae44d284e215e0efc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@181b014c06d9130455f95dcae44d284e215e0efc",
"title": "181b014c06d9130455f95dcae44d284e215e0efc",
"url": "https://llvm.googlesource.com/llvm-project/+/181b014c06d9130455f95dcae44d284e215e0efc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "84be78524de7d06943570854cbf31537c8f45199"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@84be78524de7d06943570854cbf31537c8f45199",
"title": "84be78524de7d06943570854cbf31537c8f45199",
"url": "https://llvm.googlesource.com/llvm-project/+/84be78524de7d06943570854cbf31537c8f45199"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e427bd55a852eff2b7fed97941642be121557dca"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e427bd55a852eff2b7fed97941642be121557dca",
"title": "e427bd55a852eff2b7fed97941642be121557dca",
"url": "https://llvm.googlesource.com/llvm-project/+/e427bd55a852eff2b7fed97941642be121557dca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "48ff068c525922e8323982795c69bbfbe49fea1e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@48ff068c525922e8323982795c69bbfbe49fea1e",
"title": "48ff068c525922e8323982795c69bbfbe49fea1e",
"url": "https://llvm.googlesource.com/llvm-project/+/48ff068c525922e8323982795c69bbfbe49fea1e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "18292a4ea2b690b1f9eace9ccc4d2524ab2914a7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@18292a4ea2b690b1f9eace9ccc4d2524ab2914a7",
"title": "18292a4ea2b690b1f9eace9ccc4d2524ab2914a7",
"url": "https://llvm.googlesource.com/llvm-project/+/18292a4ea2b690b1f9eace9ccc4d2524ab2914a7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ae43002cd08e320e88758a720e75b27066678a37"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ae43002cd08e320e88758a720e75b27066678a37",
"title": "ae43002cd08e320e88758a720e75b27066678a37",
"url": "https://llvm.googlesource.com/llvm-project/+/ae43002cd08e320e88758a720e75b27066678a37"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "be7e146dd3f13307f5988a5d5b652667902a1c8e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@be7e146dd3f13307f5988a5d5b652667902a1c8e",
"title": "be7e146dd3f13307f5988a5d5b652667902a1c8e",
"url": "https://llvm.googlesource.com/llvm-project/+/be7e146dd3f13307f5988a5d5b652667902a1c8e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0b533f2d9f0551aaffb13dcac8e0fd0a952185b5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0b533f2d9f0551aaffb13dcac8e0fd0a952185b5",
"title": "0b533f2d9f0551aaffb13dcac8e0fd0a952185b5",
"url": "https://llvm.googlesource.com/llvm-project/+/0b533f2d9f0551aaffb13dcac8e0fd0a952185b5"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "0b533f2d9f0551aaffb13dcac8e0fd0a952185b5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8875380208036294336"
},
{
"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"
}
}
[02:21:11.026] Scheduled build:
{
"id": "8709950146098510161",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-08T02:21:10.694528831Z",
"updateTime": "2025-07-08T02:21:10.694528831Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "0b533f2d9f0551aaffb13dcac8e0fd0a952185b5",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:21:11.026] Task URL: https://cr-buildbucket.appspot.com/build/8709950146098510161
[02:21:11.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:2:0) after 4m13s
[02:21:12.124] Received PubSub notification, asking Buildbucket for the build status
[02:21:12.142] Build status: SCHEDULED
[02:21:16.294] Received PubSub notification, asking Buildbucket for the build status
[02:21:16.365] Build status: STARTED
[02:25:24.052] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:2:0)
[02:25:24.080] Build status: STARTED
[02:25:24.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:5:0) after 9m9s
[02:34:33.134] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:5:0)
[02:34:33.156] Build status: STARTED
[02:34:33.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:6:0) after 2m40s
[02:37:13.199] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:6:0)
[02:37:13.219] Build status: STARTED
[02:37:13.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:7:0) after 2m44s
[02:39:57.281] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:7:0)
[02:39:57.303] Build status: STARTED
[02:39:57.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:8:0) after 6m20s
[02:46:17.415] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:8:0)
[02:46:17.479] Build status: STARTED
[02:46:17.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:9:0) after 4m58s
[02:51:15.538] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:9:0)
[02:51:15.560] Build status: STARTED
[02:51:15.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:10:0) after 5m17s
[02:56:32.577] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:10:0)
[02:56:32.687] Build status: STARTED
[02:56:32.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:11:0) after 1m36s
[02:58:08.689] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:11:0)
[02:58:08.723] Build status: STARTED
[02:58:08.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:12:0) after 1m42s
[02:59:50.789] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:12:0)
[02:59:50.844] Build status: STARTED
[02:59:50.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:13:0) after 5m14s
[03:05:04.858] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:13:0)
[03:05:04.882] Build status: STARTED
[03:05:04.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:14:0) after 1m29s
[03:06:33.956] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:14:0)
[03:06:34.004] Build status: STARTED
[03:06:34.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:15:0) after 6m15s
[03:12:49.107] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:15:0)
[03:12:49.130] Build status: STARTED
[03:12:49.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:16:0) after 3m58s
[03:16:47.190] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:16:0)
[03:16:47.213] Build status: STARTED
[03:16:47.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:17:0) after 6m22s
[03:23:09.254] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:17:0)
[03:23:09.273] Build status: STARTED
[03:23:09.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:18:0) after 7m30s
[03:30:39.296] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:18:0)
[03:30:39.321] Build status: STARTED
[03:30:39.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:19:0) after 6m13s
[03:36:52.450] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:19:0)
[03:36:52.471] Build status: STARTED
[03:36:52.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:20:0) after 4m33s
[03:41:25.541] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:20:0)
[03:41:25.588] Build status: STARTED
[03:41:25.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:21:0) after 7m3s
[03:48:28.656] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:21:0)
[03:48:28.701] Build status: STARTED
[03:48:28.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875380208036294336:22:0) after 5m0s
[03:52:40.741] Received PubSub notification, asking Buildbucket for the build status
[03:52:40.755] Build:
{
"id": "8709950146098510161",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-08T02:21:10.694528831Z",
"startTime": "2025-07-08T02:21:16.011484286Z",
"endTime": "2025-07-08T03:52:40.386888458Z",
"updateTime": "2025-07-08T03:52:40.386888458Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "0b533f2d9f0551aaffb13dcac8e0fd0a952185b5",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:52:40.755] Invocation finished in 1h31m31.292012765s with status SUCCEEDED