[11:39:16.691] New invocation is queued and will start shortly
[11:39:17.850] Starting the invocation (attempt 1)
[11:39:17.877] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d"
[11:39:17.877] Popped gitiles commit info from properties and tags
[11:39:17.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:39:17.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:39:17.878] Buildbucket request:
{
"requestId": "8899262693272961216",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899262693272961216",
"job": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ad45eb4a9c74a878998efe8fd734f5ae7af5003d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ad45eb4a9c74a878998efe8fd734f5ae7af5003d",
"title": "ad45eb4a9c74a878998efe8fd734f5ae7af5003d",
"url": "https://llvm.googlesource.com/llvm-project/+/ad45eb4a9c74a878998efe8fd734f5ae7af5003d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "53d89ef34005f4dc4f764db0c009130bb52a6a78"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@53d89ef34005f4dc4f764db0c009130bb52a6a78",
"title": "53d89ef34005f4dc4f764db0c009130bb52a6a78",
"url": "https://llvm.googlesource.com/llvm-project/+/53d89ef34005f4dc4f764db0c009130bb52a6a78"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5b4071c7554ab4feeae4817e3d41013016308586"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5b4071c7554ab4feeae4817e3d41013016308586",
"title": "5b4071c7554ab4feeae4817e3d41013016308586",
"url": "https://llvm.googlesource.com/llvm-project/+/5b4071c7554ab4feeae4817e3d41013016308586"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "125168744810fffff4aba039208afd9ffe1d11b1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@125168744810fffff4aba039208afd9ffe1d11b1",
"title": "125168744810fffff4aba039208afd9ffe1d11b1",
"url": "https://llvm.googlesource.com/llvm-project/+/125168744810fffff4aba039208afd9ffe1d11b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4d228e1ebdd652ad3c95e64c0f1bae17145e9e1b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4d228e1ebdd652ad3c95e64c0f1bae17145e9e1b",
"title": "4d228e1ebdd652ad3c95e64c0f1bae17145e9e1b",
"url": "https://llvm.googlesource.com/llvm-project/+/4d228e1ebdd652ad3c95e64c0f1bae17145e9e1b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "584e00a3161ca51ef9b47acb37a653aa881de0a6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@584e00a3161ca51ef9b47acb37a653aa881de0a6",
"title": "584e00a3161ca51ef9b47acb37a653aa881de0a6",
"url": "https://llvm.googlesource.com/llvm-project/+/584e00a3161ca51ef9b47acb37a653aa881de0a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2954d1f7bc8fa77c51768855d9df98f5559a5c5e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2954d1f7bc8fa77c51768855d9df98f5559a5c5e",
"title": "2954d1f7bc8fa77c51768855d9df98f5559a5c5e",
"url": "https://llvm.googlesource.com/llvm-project/+/2954d1f7bc8fa77c51768855d9df98f5559a5c5e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ab90d2793cf56758a91f7a7ae027850af2455d3e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ab90d2793cf56758a91f7a7ae027850af2455d3e",
"title": "ab90d2793cf56758a91f7a7ae027850af2455d3e",
"url": "https://llvm.googlesource.com/llvm-project/+/ab90d2793cf56758a91f7a7ae027850af2455d3e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "83953c7df107af26ebf9ab82e01623c991637199"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@83953c7df107af26ebf9ab82e01623c991637199",
"title": "83953c7df107af26ebf9ab82e01623c991637199",
"url": "https://llvm.googlesource.com/llvm-project/+/83953c7df107af26ebf9ab82e01623c991637199"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5bcc66dc009893c55aefdcd16a0ca2dad315481b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5bcc66dc009893c55aefdcd16a0ca2dad315481b",
"title": "5bcc66dc009893c55aefdcd16a0ca2dad315481b",
"url": "https://llvm.googlesource.com/llvm-project/+/5bcc66dc009893c55aefdcd16a0ca2dad315481b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "40d9561b2d5651e3d2ffa057d2b89cb8d5146fb9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@40d9561b2d5651e3d2ffa057d2b89cb8d5146fb9",
"title": "40d9561b2d5651e3d2ffa057d2b89cb8d5146fb9",
"url": "https://llvm.googlesource.com/llvm-project/+/40d9561b2d5651e3d2ffa057d2b89cb8d5146fb9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9c80eb7c83c6471d4126ef46f85bf673787de521"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9c80eb7c83c6471d4126ef46f85bf673787de521",
"title": "9c80eb7c83c6471d4126ef46f85bf673787de521",
"url": "https://llvm.googlesource.com/llvm-project/+/9c80eb7c83c6471d4126ef46f85bf673787de521"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d",
"title": "2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d",
"url": "https://llvm.googlesource.com/llvm-project/+/2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899262693272961216"
},
{
"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"
}
}
[11:39:18.353] Scheduled build:
{
"id": "8733832631293186945",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-17T11:39:17.952873332Z",
"updateTime": "2024-10-17T11:39:17.952873332Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[11:39:18.353] Task URL: https://cr-buildbucket.appspot.com/build/8733832631293186945
[11:39:18.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:2:0) after 1m11s
[11:39:19.278] Received PubSub notification, asking Buildbucket for the build status
[11:39:19.303] Build status: SCHEDULED
[11:39:22.548] Received PubSub notification, asking Buildbucket for the build status
[11:39:22.567] Build status: STARTED
[11:40:29.474] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:2:0)
[11:40:29.491] Build status: STARTED
[11:40:29.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:5:0) after 6m43s
[11:47:12.803] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:5:0)
[11:47:12.846] Build status: STARTED
[11:47:12.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:6:0) after 9m41s
[11:56:53.873] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:6:0)
[11:56:53.949] Build status: STARTED
[11:56:53.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:7:0) after 4m54s
[12:01:47.996] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:7:0)
[12:01:48.016] Build status: STARTED
[12:01:48.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:8:0) after 7m55s
[12:09:43.375] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:8:0)
[12:09:43.526] Build status: STARTED
[12:09:43.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:9:0) after 4m19s
[12:14:02.541] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:9:0)
[12:14:02.558] Build status: STARTED
[12:14:02.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:10:0) after 8m4s
[12:22:06.577] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:10:0)
[12:22:06.599] Build status: STARTED
[12:22:06.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:11:0) after 9m12s
[12:31:18.923] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:11:0)
[12:31:18.973] Build status: STARTED
[12:31:18.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:12:0) after 2m40s
[12:33:59.299] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:12:0)
[12:33:59.325] Build status: STARTED
[12:33:59.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:13:0) after 7m57s
[12:41:56.344] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:13:0)
[12:41:56.360] Build status: STARTED
[12:41:56.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:14:0) after 2m23s
[12:44:19.362] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:14:0)
[12:44:19.379] Build status: STARTED
[12:44:19.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:15:0) after 4m28s
[12:48:47.820] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:15:0)
[12:48:47.846] Build status: STARTED
[12:48:47.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8899262693272961216:16:0) after 6m9s
[12:49:47.765] Received PubSub notification, asking Buildbucket for the build status
[12:49:47.784] Build:
{
"id": "8733832631293186945",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-17T11:39:17.952873332Z",
"startTime": "2024-10-17T11:39:22.342762840Z",
"endTime": "2024-10-17T12:49:47.251218630Z",
"updateTime": "2024-10-17T12:49:47.251218630Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2f0b4f43fc5c1e7587c4d00daa9cc230df2f8a2d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[12:49:47.784] Invocation finished in 1h10m31.10299076s with status SUCCEEDED