[17:41:54.192] New invocation is queued and will start shortly
[17:41:55.327] Starting the invocation (attempt 1)
[17:41:55.354] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/38b8ef16f76f59b614d2100ebc8e987c310382ae"
[17:41:55.354] Popped gitiles commit info from properties and tags
[17:41:55.354] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:41:55.355] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:41:55.356] Buildbucket request:
{
"requestId": "8875594070651376560",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8875594070651376560",
"job": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f17a6387daf0e2edc2123ce3dacc267d1be5e45e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f17a6387daf0e2edc2123ce3dacc267d1be5e45e",
"title": "f17a6387daf0e2edc2123ce3dacc267d1be5e45e",
"url": "https://llvm.googlesource.com/llvm-project/+/f17a6387daf0e2edc2123ce3dacc267d1be5e45e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "113ea3d15be0dcf734983d14ac83832f32bc1248"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@113ea3d15be0dcf734983d14ac83832f32bc1248",
"title": "113ea3d15be0dcf734983d14ac83832f32bc1248",
"url": "https://llvm.googlesource.com/llvm-project/+/113ea3d15be0dcf734983d14ac83832f32bc1248"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9eb4fc7006364a106e13bbaad815905586ab1dc6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9eb4fc7006364a106e13bbaad815905586ab1dc6",
"title": "9eb4fc7006364a106e13bbaad815905586ab1dc6",
"url": "https://llvm.googlesource.com/llvm-project/+/9eb4fc7006364a106e13bbaad815905586ab1dc6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3448e9c075aa27e6d4fdc1626bbf6291a0892614"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3448e9c075aa27e6d4fdc1626bbf6291a0892614",
"title": "3448e9c075aa27e6d4fdc1626bbf6291a0892614",
"url": "https://llvm.googlesource.com/llvm-project/+/3448e9c075aa27e6d4fdc1626bbf6291a0892614"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7d65cb1952dc77f1562659fafcb512ddd1a488fa"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7d65cb1952dc77f1562659fafcb512ddd1a488fa",
"title": "7d65cb1952dc77f1562659fafcb512ddd1a488fa",
"url": "https://llvm.googlesource.com/llvm-project/+/7d65cb1952dc77f1562659fafcb512ddd1a488fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b94a9bc741e5d59df11a4b167e6311661a2551d2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b94a9bc741e5d59df11a4b167e6311661a2551d2",
"title": "b94a9bc741e5d59df11a4b167e6311661a2551d2",
"url": "https://llvm.googlesource.com/llvm-project/+/b94a9bc741e5d59df11a4b167e6311661a2551d2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "38b8ef16f76f59b614d2100ebc8e987c310382ae"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@38b8ef16f76f59b614d2100ebc8e987c310382ae",
"title": "38b8ef16f76f59b614d2100ebc8e987c310382ae",
"url": "https://llvm.googlesource.com/llvm-project/+/38b8ef16f76f59b614d2100ebc8e987c310382ae"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "38b8ef16f76f59b614d2100ebc8e987c310382ae",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8875594070651376560"
},
{
"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:41:55.738] Scheduled build:
{
"id": "8710164008720636897",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-05T17:41:55.405883832Z",
"updateTime": "2025-07-05T17:41:55.405883832Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "38b8ef16f76f59b614d2100ebc8e987c310382ae",
"ref": "refs/heads/main"
}
}
}
[17:41:55.738] Task URL: https://cr-buildbucket.appspot.com/build/8710164008720636897
[17:41:55.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:2:0) after 6m47s
[17:41:56.694] Received PubSub notification, asking Buildbucket for the build status
[17:41:56.713] Build status: SCHEDULED
[17:41:59.036] Received PubSub notification, asking Buildbucket for the build status
[17:41:59.058] Build status: STARTED
[17:48:42.755] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:2:0)
[17:48:42.777] Build status: STARTED
[17:48:42.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:5:0) after 6m5s
[17:54:47.799] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:5:0)
[17:54:47.817] Build status: STARTED
[17:54:47.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:6:0) after 3m9s
[17:57:56.850] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:6:0)
[17:57:56.867] Build status: STARTED
[17:57:56.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:7:0) after 4m47s
[18:02:43.887] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:7:0)
[18:02:43.956] Build status: STARTED
[18:02:43.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:8:0) after 8m28s
[18:11:11.987] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:8:0)
[18:11:12.014] Build status: STARTED
[18:11:12.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:9:0) after 7m8s
[18:18:20.041] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:9:0)
[18:18:20.068] Build status: STARTED
[18:18:20.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:10:0) after 1m54s
[18:20:14.110] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:10:0)
[18:20:14.152] Build status: STARTED
[18:20:14.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:11:0) after 7m20s
[18:27:34.267] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:11:0)
[18:27:34.297] Build status: STARTED
[18:27:34.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:12:0) after 8m56s
[18:36:30.333] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:12:0)
[18:36:30.354] Build status: STARTED
[18:36:30.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:13:0) after 5m44s
[18:42:14.373] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:13:0)
[18:42:14.398] Build status: STARTED
[18:42:14.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:14:0) after 5m24s
[18:47:38.418] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:14:0)
[18:47:38.467] Build status: STARTED
[18:47:38.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:15:0) after 8m58s
[18:56:36.485] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:15:0)
[18:56:36.505] Build status: STARTED
[18:56:36.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:16:0) after 2m34s
[18:59:10.510] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:16:0)
[18:59:10.526] Build status: STARTED
[18:59:10.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:17:0) after 7m32s
[19:06:42.549] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:17:0)
[19:06:42.618] Build status: STARTED
[19:06:42.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8875594070651376560:18:0) after 5m14s
[19:11:43.625] Received PubSub notification, asking Buildbucket for the build status
[19:11:43.642] Build:
{
"id": "8710164008720636897",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-05T17:41:55.405883832Z",
"startTime": "2025-07-05T17:41:58.827491649Z",
"endTime": "2025-07-05T19:11:43.172616130Z",
"updateTime": "2025-07-05T19:11:43.172616130Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "38b8ef16f76f59b614d2100ebc8e987c310382ae",
"ref": "refs/heads/main"
}
}
}
[19:11:43.642] Invocation finished in 1h29m49.461783692s with status SUCCEEDED