[18:43:18.336] New invocation is queued and will start shortly
[18:43:19.406] Starting the invocation (attempt 1)
[18:43:19.437] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/20a829937cc8cd69170b75c0bb7f31ad9ba19677"
[18:43:19.437] Popped gitiles commit info from properties and tags
[18:43:19.437] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:43:19.437] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:43:19.438] Buildbucket request:
{
"requestId": "8871966328885056304",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8871966328885056304",
"job": "fuchsia/prod-clang-ci-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "45066c293702657426d2b1754b6c92efeb022d72"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@45066c293702657426d2b1754b6c92efeb022d72",
"title": "45066c293702657426d2b1754b6c92efeb022d72",
"url": "https://llvm.googlesource.com/llvm-project/+/45066c293702657426d2b1754b6c92efeb022d72"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "44df9826f312ee615567a7b41aaebfc0e3af9286"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@44df9826f312ee615567a7b41aaebfc0e3af9286",
"title": "44df9826f312ee615567a7b41aaebfc0e3af9286",
"url": "https://llvm.googlesource.com/llvm-project/+/44df9826f312ee615567a7b41aaebfc0e3af9286"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "86482dffbafaf1ec61d9d01868837b05b5d88c6f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@86482dffbafaf1ec61d9d01868837b05b5d88c6f",
"title": "86482dffbafaf1ec61d9d01868837b05b5d88c6f",
"url": "https://llvm.googlesource.com/llvm-project/+/86482dffbafaf1ec61d9d01868837b05b5d88c6f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a8f1f1b41f3b8ed75155a5df6c85d88a5b1e3bdb"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a8f1f1b41f3b8ed75155a5df6c85d88a5b1e3bdb",
"title": "a8f1f1b41f3b8ed75155a5df6c85d88a5b1e3bdb",
"url": "https://llvm.googlesource.com/llvm-project/+/a8f1f1b41f3b8ed75155a5df6c85d88a5b1e3bdb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ada191136b17daac3d53e7919eaa9a570de06798"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ada191136b17daac3d53e7919eaa9a570de06798",
"title": "ada191136b17daac3d53e7919eaa9a570de06798",
"url": "https://llvm.googlesource.com/llvm-project/+/ada191136b17daac3d53e7919eaa9a570de06798"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "efce767a8803bb610bdb712562016c517fc7159a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@efce767a8803bb610bdb712562016c517fc7159a",
"title": "efce767a8803bb610bdb712562016c517fc7159a",
"url": "https://llvm.googlesource.com/llvm-project/+/efce767a8803bb610bdb712562016c517fc7159a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9a28783f5d6234571ffe7d4286b7d273d6e137f4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9a28783f5d6234571ffe7d4286b7d273d6e137f4",
"title": "9a28783f5d6234571ffe7d4286b7d273d6e137f4",
"url": "https://llvm.googlesource.com/llvm-project/+/9a28783f5d6234571ffe7d4286b7d273d6e137f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "91418ecbdef0e259f83e6ddac5ddfc22a8b6eced"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@91418ecbdef0e259f83e6ddac5ddfc22a8b6eced",
"title": "91418ecbdef0e259f83e6ddac5ddfc22a8b6eced",
"url": "https://llvm.googlesource.com/llvm-project/+/91418ecbdef0e259f83e6ddac5ddfc22a8b6eced"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ac0ad5093ab451a36ea9ed34b0d3d461e759f4ea"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ac0ad5093ab451a36ea9ed34b0d3d461e759f4ea",
"title": "ac0ad5093ab451a36ea9ed34b0d3d461e759f4ea",
"url": "https://llvm.googlesource.com/llvm-project/+/ac0ad5093ab451a36ea9ed34b0d3d461e759f4ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dc0becc4d09bba0fad07ae5e8b98219297432496"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dc0becc4d09bba0fad07ae5e8b98219297432496",
"title": "dc0becc4d09bba0fad07ae5e8b98219297432496",
"url": "https://llvm.googlesource.com/llvm-project/+/dc0becc4d09bba0fad07ae5e8b98219297432496"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e27e4f3a99911675215ca79eb95290a3ab77fe74"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e27e4f3a99911675215ca79eb95290a3ab77fe74",
"title": "e27e4f3a99911675215ca79eb95290a3ab77fe74",
"url": "https://llvm.googlesource.com/llvm-project/+/e27e4f3a99911675215ca79eb95290a3ab77fe74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ff0ce74be84bb1300dc5790842c85ad1f3d88174"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ff0ce74be84bb1300dc5790842c85ad1f3d88174",
"title": "ff0ce74be84bb1300dc5790842c85ad1f3d88174",
"url": "https://llvm.googlesource.com/llvm-project/+/ff0ce74be84bb1300dc5790842c85ad1f3d88174"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c202d2f5157256208dcd2e46d910e633f52b74ad"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c202d2f5157256208dcd2e46d910e633f52b74ad",
"title": "c202d2f5157256208dcd2e46d910e633f52b74ad",
"url": "https://llvm.googlesource.com/llvm-project/+/c202d2f5157256208dcd2e46d910e633f52b74ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f5d284309fe4f4e83f879f08356d149000bbc762"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f5d284309fe4f4e83f879f08356d149000bbc762",
"title": "f5d284309fe4f4e83f879f08356d149000bbc762",
"url": "https://llvm.googlesource.com/llvm-project/+/f5d284309fe4f4e83f879f08356d149000bbc762"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "37cc010b91e0aaeced53d9261c1f4b511f603871"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@37cc010b91e0aaeced53d9261c1f4b511f603871",
"title": "37cc010b91e0aaeced53d9261c1f4b511f603871",
"url": "https://llvm.googlesource.com/llvm-project/+/37cc010b91e0aaeced53d9261c1f4b511f603871"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "98728d9dc8f38703bc3ebbbf32d45567acaa75cc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@98728d9dc8f38703bc3ebbbf32d45567acaa75cc",
"title": "98728d9dc8f38703bc3ebbbf32d45567acaa75cc",
"url": "https://llvm.googlesource.com/llvm-project/+/98728d9dc8f38703bc3ebbbf32d45567acaa75cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "db98ac43ec13805e1876c389933eb7155ee1f073"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@db98ac43ec13805e1876c389933eb7155ee1f073",
"title": "db98ac43ec13805e1876c389933eb7155ee1f073",
"url": "https://llvm.googlesource.com/llvm-project/+/db98ac43ec13805e1876c389933eb7155ee1f073"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "20a829937cc8cd69170b75c0bb7f31ad9ba19677"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@20a829937cc8cd69170b75c0bb7f31ad9ba19677",
"title": "20a829937cc8cd69170b75c0bb7f31ad9ba19677",
"url": "https://llvm.googlesource.com/llvm-project/+/20a829937cc8cd69170b75c0bb7f31ad9ba19677"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "20a829937cc8cd69170b75c0bb7f31ad9ba19677",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871966328885056304"
},
{
"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:43:19.825] Scheduled build:
{
"id": "8706536267018627809",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-14T18:43:19.492083683Z",
"updateTime": "2025-08-14T18:43:19.492083683Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "20a829937cc8cd69170b75c0bb7f31ad9ba19677",
"ref": "refs/heads/main"
}
}
}
[18:43:19.825] Task URL: https://cr-buildbucket.appspot.com/build/8706536267018627809
[18:43:19.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:2:0) after 5m47s
[18:43:37.093] Received PubSub notification, asking Buildbucket for the build status
[18:43:37.127] Build status: SCHEDULED
[18:49:06.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:2:0)
[18:49:06.965] Build status: SCHEDULED
[18:49:06.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:4:0) after 5m0s
[18:54:06.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:4:0)
[18:54:07.053] Build status: SCHEDULED
[18:54:07.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:5:0) after 6m39s
[19:00:46.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:5:0)
[19:00:46.101] Build status: SCHEDULED
[19:00:46.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:6:0) after 6m11s
[19:06:57.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:6:0)
[19:06:57.251] Build status: SCHEDULED
[19:06:57.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:7:0) after 2m23s
[19:09:20.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:7:0)
[19:09:20.287] Build status: SCHEDULED
[19:09:20.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:8:0) after 3m57s
[19:13:17.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:8:0)
[19:13:17.377] Build status: SCHEDULED
[19:13:17.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:9:0) after 4m7s
[19:17:24.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:9:0)
[19:17:24.459] Build status: SCHEDULED
[19:17:24.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:10:0) after 9m42s
[19:27:06.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:10:0)
[19:27:06.555] Build status: SCHEDULED
[19:27:06.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:11:0) after 2m42s
[19:29:48.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:11:0)
[19:29:48.672] Build status: SCHEDULED
[19:29:48.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:12:0) after 6m14s
[19:36:02.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:12:0)
[19:36:02.698] Build status: SCHEDULED
[19:36:02.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:13:0) after 9m12s
[19:45:14.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:13:0)
[19:45:14.833] Build status: SCHEDULED
[19:45:14.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:14:0) after 9m39s
[19:54:53.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:14:0)
[19:54:53.954] Build status: SCHEDULED
[19:54:53.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:15:0) after 5m27s
[19:55:56.906] Received PubSub notification, asking Buildbucket for the build status
[19:55:56.951] Build status: STARTED
[20:00:20.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:15:0)
[20:00:21.004] Build status: STARTED
[20:00:21.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:17:0) after 2m3s
[20:02:24.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:17:0)
[20:02:24.123] Build status: STARTED
[20:02:24.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:18:0) after 7m14s
[20:09:38.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:18:0)
[20:09:38.184] Build status: STARTED
[20:09:38.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:19:0) after 2m3s
[20:11:41.254] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:19:0)
[20:11:41.281] Build status: STARTED
[20:11:41.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:20:0) after 7m58s
[20:19:39.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:20:0)
[20:19:39.471] Build status: STARTED
[20:19:39.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:21:0) after 1m24s
[20:21:03.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:21:0)
[20:21:03.558] Build status: STARTED
[20:21:03.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:22:0) after 2m4s
[20:23:07.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:22:0)
[20:23:07.627] Build status: STARTED
[20:23:07.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:23:0) after 8m51s
[20:31:58.647] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:23:0)
[20:31:58.666] Build status: STARTED
[20:31:58.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:24:0) after 5m17s
[20:37:15.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:24:0)
[20:37:15.791] Build status: STARTED
[20:37:15.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:25:0) after 2m7s
[20:39:22.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:25:0)
[20:39:22.833] Build status: STARTED
[20:39:22.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:26:0) after 1m35s
[20:40:57.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:26:0)
[20:40:57.905] Build status: STARTED
[20:40:57.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:27:0) after 8m53s
[20:49:50.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:27:0)
[20:49:50.988] Build status: STARTED
[20:49:50.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:28:0) after 6m54s
[20:56:45.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:28:0)
[20:56:45.045] Build status: STARTED
[20:56:45.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:29:0) after 6m30s
[21:03:15.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:29:0)
[21:03:15.203] Build status: STARTED
[21:03:15.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8871966328885056304:30:0) after 5m38s
[21:04:45.399] Received PubSub notification, asking Buildbucket for the build status
[21:04:45.415] Build:
{
"id": "8706536267018627809",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-14T18:43:19.492083683Z",
"startTime": "2025-08-14T19:55:56.494409704Z",
"endTime": "2025-08-14T21:04:45.030888955Z",
"updateTime": "2025-08-14T21:04:45.030888955Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "20a829937cc8cd69170b75c0bb7f31ad9ba19677",
"ref": "refs/heads/main"
}
}
}
[21:04:45.415] Invocation finished in 2h21m27.085505197s with status FAILED