[13:18:02.302] New invocation is queued and will start shortly
[13:18:03.373] Starting the invocation (attempt 1)
[13:18:03.399] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/1f30e3d123ee7f7e0a98dc4057b5d280a490c723"
[13:18:03.399] Popped gitiles commit info from properties and tags
[13:18:03.399] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:18:03.399] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:18:03.399] Buildbucket request:
{
"requestId": "8878237983622111888",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8878237983622111888",
"job": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4455d9d3e333ab780a642c534560f10b519a865c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4455d9d3e333ab780a642c534560f10b519a865c",
"title": "4455d9d3e333ab780a642c534560f10b519a865c",
"url": "https://llvm.googlesource.com/llvm-project/+/4455d9d3e333ab780a642c534560f10b519a865c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "269c29ae67e54bd3243535a7c58d0f30d1eb9036"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@269c29ae67e54bd3243535a7c58d0f30d1eb9036",
"title": "269c29ae67e54bd3243535a7c58d0f30d1eb9036",
"url": "https://llvm.googlesource.com/llvm-project/+/269c29ae67e54bd3243535a7c58d0f30d1eb9036"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "89d2d62e4682a32851083a1e32eb64fb7364c39c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@89d2d62e4682a32851083a1e32eb64fb7364c39c",
"title": "89d2d62e4682a32851083a1e32eb64fb7364c39c",
"url": "https://llvm.googlesource.com/llvm-project/+/89d2d62e4682a32851083a1e32eb64fb7364c39c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1540ed52eeb25d6cf8142c1071d85a50f5c167b1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1540ed52eeb25d6cf8142c1071d85a50f5c167b1",
"title": "1540ed52eeb25d6cf8142c1071d85a50f5c167b1",
"url": "https://llvm.googlesource.com/llvm-project/+/1540ed52eeb25d6cf8142c1071d85a50f5c167b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4e676a13171c8af7fde20c1d2de9668f68b4a920"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4e676a13171c8af7fde20c1d2de9668f68b4a920",
"title": "4e676a13171c8af7fde20c1d2de9668f68b4a920",
"url": "https://llvm.googlesource.com/llvm-project/+/4e676a13171c8af7fde20c1d2de9668f68b4a920"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b80024e0f4bea449f5c1373436cd61096dd6613b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b80024e0f4bea449f5c1373436cd61096dd6613b",
"title": "b80024e0f4bea449f5c1373436cd61096dd6613b",
"url": "https://llvm.googlesource.com/llvm-project/+/b80024e0f4bea449f5c1373436cd61096dd6613b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "df48dfa0aece477f8f9990e26e91b43969851559"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@df48dfa0aece477f8f9990e26e91b43969851559",
"title": "df48dfa0aece477f8f9990e26e91b43969851559",
"url": "https://llvm.googlesource.com/llvm-project/+/df48dfa0aece477f8f9990e26e91b43969851559"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bfbf5d5da6363886262cc2c071b9c2e4cf8d7462"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bfbf5d5da6363886262cc2c071b9c2e4cf8d7462",
"title": "bfbf5d5da6363886262cc2c071b9c2e4cf8d7462",
"url": "https://llvm.googlesource.com/llvm-project/+/bfbf5d5da6363886262cc2c071b9c2e4cf8d7462"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "efc70787b5442fa189de7222be4c1275abb556ba"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@efc70787b5442fa189de7222be4c1275abb556ba",
"title": "efc70787b5442fa189de7222be4c1275abb556ba",
"url": "https://llvm.googlesource.com/llvm-project/+/efc70787b5442fa189de7222be4c1275abb556ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1f30e3d123ee7f7e0a98dc4057b5d280a490c723"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1f30e3d123ee7f7e0a98dc4057b5d280a490c723",
"title": "1f30e3d123ee7f7e0a98dc4057b5d280a490c723",
"url": "https://llvm.googlesource.com/llvm-project/+/1f30e3d123ee7f7e0a98dc4057b5d280a490c723"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1f30e3d123ee7f7e0a98dc4057b5d280a490c723",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878237983622111888"
},
{
"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"
}
}
[13:18:03.794] Scheduled build:
{
"id": "8712807921751597585",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-06-06T13:18:03.452898635Z",
"updateTime": "2025-06-06T13:18:03.452898635Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1f30e3d123ee7f7e0a98dc4057b5d280a490c723",
"ref": "refs/heads/main"
}
},
"canary": true
}
[13:18:03.794] Task URL: https://cr-buildbucket.appspot.com/build/8712807921751597585
[13:18:03.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:2:0) after 4m50s
[13:18:04.795] Received PubSub notification, asking Buildbucket for the build status
[13:18:04.825] Build status: SCHEDULED
[13:18:07.307] Received PubSub notification, asking Buildbucket for the build status
[13:18:07.344] Build status: STARTED
[13:22:53.860] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:2:0)
[13:22:53.887] Build status: STARTED
[13:22:53.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:5:0) after 2m29s
[13:25:22.892] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:5:0)
[13:25:22.909] Build status: STARTED
[13:25:22.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:6:0) after 5m37s
[13:30:59.962] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:6:0)
[13:30:59.988] Build status: STARTED
[13:30:59.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:7:0) after 9m56s
[13:40:56.073] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:7:0)
[13:40:56.090] Build status: STARTED
[13:40:56.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:8:0) after 4m46s
[13:45:42.110] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:8:0)
[13:45:42.147] Build status: STARTED
[13:45:42.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:9:0) after 5m49s
[13:51:31.152] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:9:0)
[13:51:31.176] Build status: STARTED
[13:51:31.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:10:0) after 1m3s
[13:52:34.246] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:10:0)
[13:52:34.275] Build status: STARTED
[13:52:34.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:11:0) after 6m54s
[13:59:28.309] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:11:0)
[13:59:28.359] Build status: STARTED
[13:59:28.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:12:0) after 5m52s
[14:05:20.385] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:12:0)
[14:05:20.404] Build status: STARTED
[14:05:20.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:13:0) after 8m6s
[14:13:26.428] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:13:0)
[14:13:26.462] Build status: STARTED
[14:13:26.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:14:0) after 4m4s
[14:17:30.613] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:14:0)
[14:17:30.629] Build status: STARTED
[14:17:30.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:15:0) after 7m53s
[14:25:23.759] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:15:0)
[14:25:23.852] Build status: STARTED
[14:25:23.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:16:0) after 6m4s
[14:31:27.868] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:16:0)
[14:31:27.891] Build status: STARTED
[14:31:27.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:17:0) after 1m38s
[14:33:05.981] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:17:0)
[14:33:05.998] Build status: STARTED
[14:33:05.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:18:0) after 6m8s
[14:39:14.017] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:18:0)
[14:39:14.040] Build status: STARTED
[14:39:14.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:19:0) after 4m34s
[14:43:48.116] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:19:0)
[14:43:48.135] Build status: STARTED
[14:43:48.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8878237983622111888:20:0) after 7m52s
[14:45:50.298] Received PubSub notification, asking Buildbucket for the build status
[14:45:50.326] Build:
{
"id": "8712807921751597585",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-06-06T13:18:03.452898635Z",
"startTime": "2025-06-06T13:18:07.105902379Z",
"endTime": "2025-06-06T14:45:24.812001033Z",
"updateTime": "2025-06-06T14:45:24.812001033Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1f30e3d123ee7f7e0a98dc4057b5d280a490c723",
"ref": "refs/heads/main"
}
},
"canary": true
}
[14:45:50.326] Invocation finished in 1h27m48.040421448s with status SUCCEEDED