[18:12:44.641] New invocation is queued and will start shortly
[18:12:46.234] Starting the invocation (attempt 1)
[18:12:46.261] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/d65e712e30a8998c897a6454e4eaea4f974bf765"
[18:12:46.261] Popped gitiles commit info from properties and tags
[18:12:46.261] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:12:46.261] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:12:46.262] Buildbucket request:
{
"requestId": "8864629897379059168",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8864629897379059168",
"job": "fuchsia/prod-clang-ci-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "645a9ed7f26c7454b9973239a6b737ebdb42277b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@645a9ed7f26c7454b9973239a6b737ebdb42277b",
"title": "645a9ed7f26c7454b9973239a6b737ebdb42277b",
"url": "https://llvm.googlesource.com/llvm-project/+/645a9ed7f26c7454b9973239a6b737ebdb42277b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4cb8f97a0965f0a1138ab9f16aa00aff9b06c312"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4cb8f97a0965f0a1138ab9f16aa00aff9b06c312",
"title": "4cb8f97a0965f0a1138ab9f16aa00aff9b06c312",
"url": "https://llvm.googlesource.com/llvm-project/+/4cb8f97a0965f0a1138ab9f16aa00aff9b06c312"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5f0169939165c308f26585211860153aa8f0cef3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5f0169939165c308f26585211860153aa8f0cef3",
"title": "5f0169939165c308f26585211860153aa8f0cef3",
"url": "https://llvm.googlesource.com/llvm-project/+/5f0169939165c308f26585211860153aa8f0cef3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "97660c109470aeab78d9602328b7dc7dbb0f9091"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@97660c109470aeab78d9602328b7dc7dbb0f9091",
"title": "97660c109470aeab78d9602328b7dc7dbb0f9091",
"url": "https://llvm.googlesource.com/llvm-project/+/97660c109470aeab78d9602328b7dc7dbb0f9091"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a85ecfa7a8ca20bd7710ab55e88ff7a60c63a5a3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a85ecfa7a8ca20bd7710ab55e88ff7a60c63a5a3",
"title": "a85ecfa7a8ca20bd7710ab55e88ff7a60c63a5a3",
"url": "https://llvm.googlesource.com/llvm-project/+/a85ecfa7a8ca20bd7710ab55e88ff7a60c63a5a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6a275de13f6cdf927cf3cd6125ea858ddc0c4b1d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6a275de13f6cdf927cf3cd6125ea858ddc0c4b1d",
"title": "6a275de13f6cdf927cf3cd6125ea858ddc0c4b1d",
"url": "https://llvm.googlesource.com/llvm-project/+/6a275de13f6cdf927cf3cd6125ea858ddc0c4b1d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "513334faec2594bbeb3ac00f0092bed20b23abd3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@513334faec2594bbeb3ac00f0092bed20b23abd3",
"title": "513334faec2594bbeb3ac00f0092bed20b23abd3",
"url": "https://llvm.googlesource.com/llvm-project/+/513334faec2594bbeb3ac00f0092bed20b23abd3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c4763e2b9038fbd1154f0276a8b9542b8c115111"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c4763e2b9038fbd1154f0276a8b9542b8c115111",
"title": "c4763e2b9038fbd1154f0276a8b9542b8c115111",
"url": "https://llvm.googlesource.com/llvm-project/+/c4763e2b9038fbd1154f0276a8b9542b8c115111"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e8765401d49cf0b5775391f2eaa8e44a292e2caf"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e8765401d49cf0b5775391f2eaa8e44a292e2caf",
"title": "e8765401d49cf0b5775391f2eaa8e44a292e2caf",
"url": "https://llvm.googlesource.com/llvm-project/+/e8765401d49cf0b5775391f2eaa8e44a292e2caf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6747ea050dfc917b78c205102d9545902140ea2d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6747ea050dfc917b78c205102d9545902140ea2d",
"title": "6747ea050dfc917b78c205102d9545902140ea2d",
"url": "https://llvm.googlesource.com/llvm-project/+/6747ea050dfc917b78c205102d9545902140ea2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "10349ca139068f6589f47369e1d48d06aeb66ad0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@10349ca139068f6589f47369e1d48d06aeb66ad0",
"title": "10349ca139068f6589f47369e1d48d06aeb66ad0",
"url": "https://llvm.googlesource.com/llvm-project/+/10349ca139068f6589f47369e1d48d06aeb66ad0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2de5a17e9c8e427c75565574e8bdb5b62c35fec6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2de5a17e9c8e427c75565574e8bdb5b62c35fec6",
"title": "2de5a17e9c8e427c75565574e8bdb5b62c35fec6",
"url": "https://llvm.googlesource.com/llvm-project/+/2de5a17e9c8e427c75565574e8bdb5b62c35fec6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d65e712e30a8998c897a6454e4eaea4f974bf765"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d65e712e30a8998c897a6454e4eaea4f974bf765",
"title": "d65e712e30a8998c897a6454e4eaea4f974bf765",
"url": "https://llvm.googlesource.com/llvm-project/+/d65e712e30a8998c897a6454e4eaea4f974bf765"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "d65e712e30a8998c897a6454e4eaea4f974bf765",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8864629897379059168"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-clang-ci-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:12:46.664] Scheduled build:
{
"id": "8699199834955933441",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-03T18:12:46.325236858Z",
"updateTime": "2025-11-03T18:12:46.325236858Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "d65e712e30a8998c897a6454e4eaea4f974bf765",
"ref": "refs/heads/main"
}
}
}
[18:12:46.664] Task URL: https://cr-buildbucket.appspot.com/build/8699199834955933441
[18:12:46.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:2:0) after 6m7s
[18:12:47.600] Received PubSub notification, asking Buildbucket for the build status
[18:12:47.634] Build status: SCHEDULED
[18:18:53.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:2:0)
[18:18:53.699] Build status: SCHEDULED
[18:18:53.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:4:0) after 3m41s
[18:22:34.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:4:0)
[18:22:34.737] Build status: SCHEDULED
[18:22:34.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:5:0) after 8m15s
[18:30:49.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:5:0)
[18:30:49.911] Build status: SCHEDULED
[18:30:49.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:6:0) after 4m54s
[18:35:43.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:6:0)
[18:35:43.957] Build status: SCHEDULED
[18:35:43.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:7:0) after 9m56s
[18:45:40.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:7:0)
[18:45:40.137] Build status: SCHEDULED
[18:45:40.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:8:0) after 4m26s
[18:50:06.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:8:0)
[18:50:06.174] Build status: SCHEDULED
[18:50:06.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:9:0) after 3m54s
[18:54:00.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:9:0)
[18:54:00.252] Build status: SCHEDULED
[18:54:00.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:10:0) after 7m28s
[19:01:28.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:10:0)
[19:01:28.498] Build status: SCHEDULED
[19:01:28.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:11:0) after 4m11s
[19:05:39.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:11:0)
[19:05:39.579] Build status: SCHEDULED
[19:05:39.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:12:0) after 7m50s
[19:13:29.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:12:0)
[19:13:29.637] Build status: SCHEDULED
[19:13:29.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:13:0) after 2m40s
[19:16:09.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:13:0)
[19:16:09.825] Build status: SCHEDULED
[19:16:09.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:14:0) after 7m10s
[19:23:19.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:14:0)
[19:23:19.850] Build status: SCHEDULED
[19:23:19.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:15:0) after 4m18s
[19:27:37.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:15:0)
[19:27:37.937] Build status: SCHEDULED
[19:27:37.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:16:0) after 2m32s
[19:30:09.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:16:0)
[19:30:10.067] Build status: SCHEDULED
[19:30:10.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:17:0) after 2m13s
[19:32:23.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:17:0)
[19:32:23.130] Build status: SCHEDULED
[19:32:23.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:18:0) after 9m50s
[19:42:13.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:18:0)
[19:42:13.180] Build status: SCHEDULED
[19:42:13.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:19:0) after 2m13s
[19:44:26.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:19:0)
[19:44:26.224] Build status: SCHEDULED
[19:44:26.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:20:0) after 2m3s
[19:46:29.250] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:20:0)
[19:46:29.279] Build status: SCHEDULED
[19:46:29.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:21:0) after 2m2s
[19:48:31.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:21:0)
[19:48:31.361] Build status: SCHEDULED
[19:48:31.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:22:0) after 6m6s
[19:54:37.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:22:0)
[19:54:37.397] Build status: SCHEDULED
[19:54:37.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:23:0) after 5m19s
[19:59:56.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:23:0)
[19:59:56.441] Build status: SCHEDULED
[19:59:56.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:24:0) after 7m24s
[20:07:20.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:24:0)
[20:07:20.518] Build status: SCHEDULED
[20:07:20.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:25:0) after 5m15s
[20:12:35.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:25:0)
[20:12:35.518] Build status: SCHEDULED
[20:12:35.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:26:0) after 6m40s
[20:19:15.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:26:0)
[20:19:15.554] Build status: SCHEDULED
[20:19:15.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:27:0) after 5m39s
[20:24:54.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:27:0)
[20:24:54.584] Build status: SCHEDULED
[20:24:54.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:28:0) after 9m42s
[20:33:30.706] Received PubSub notification, asking Buildbucket for the build status
[20:33:30.718] Build status: STARTED
[20:34:36.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:28:0)
[20:34:36.665] Build status: STARTED
[20:34:36.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:30:0) after 9m4s
[20:43:40.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:30:0)
[20:43:40.714] Build status: STARTED
[20:43:40.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:31:0) after 9m45s
[20:53:25.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:31:0)
[20:53:25.898] Build status: STARTED
[20:53:25.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:32:0) after 1m44s
[20:55:10.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:32:0)
[20:55:10.103] Build status: STARTED
[20:55:10.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:33:0) after 8m14s
[21:03:24.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:33:0)
[21:03:24.139] Build status: STARTED
[21:03:24.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:34:0) after 9m23s
[21:12:47.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:34:0)
[21:12:47.203] Build status: STARTED
[21:12:47.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:35:0) after 8m26s
[21:21:13.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:35:0)
[21:21:13.237] Build status: STARTED
[21:21:13.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:36:0) after 8m4s
[21:29:17.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:36:0)
[21:29:17.337] Build status: STARTED
[21:29:17.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:37:0) after 8m33s
[21:37:50.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:37:0)
[21:37:50.558] Build status: STARTED
[21:37:50.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8864629897379059168:38:0) after 7m22s
[21:41:47.424] Received PubSub notification, asking Buildbucket for the build status
[21:41:47.486] Build:
{
"id": "8699199834955933441",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-03T18:12:46.325236858Z",
"startTime": "2025-11-03T20:33:27.869711947Z",
"endTime": "2025-11-03T21:41:47.125891744Z",
"updateTime": "2025-11-03T21:41:47.125891744Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "d65e712e30a8998c897a6454e4eaea4f974bf765",
"ref": "refs/heads/main"
}
}
}
[21:41:47.486] Invocation finished in 3h29m2.856089821s with status SUCCEEDED