[09:15:49.705] New invocation is queued and will start shortly
[09:15:51.095] Starting the invocation (attempt 1)
[09:15:51.119] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/a20fc93a65f763cc3f55988847b7294fcbdcb5db"
[09:15:51.119] Popped gitiles commit info from properties and tags
[09:15:51.119] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:15:51.120] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:15:51.121] Buildbucket request:
{
"requestId": "8869737107306249968",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-ci-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8869737107306249968",
"job": "fuchsia/prod-rust-llvm-ci-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8ba0d0f72f25229cfa2de9b7352866d7a33c1e54"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8ba0d0f72f25229cfa2de9b7352866d7a33c1e54",
"title": "8ba0d0f72f25229cfa2de9b7352866d7a33c1e54",
"url": "https://llvm.googlesource.com/llvm-project/+/8ba0d0f72f25229cfa2de9b7352866d7a33c1e54"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2308d7bd7744fa7645b182ac8b5b6e1a8b65e65d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2308d7bd7744fa7645b182ac8b5b6e1a8b65e65d",
"title": "2308d7bd7744fa7645b182ac8b5b6e1a8b65e65d",
"url": "https://llvm.googlesource.com/llvm-project/+/2308d7bd7744fa7645b182ac8b5b6e1a8b65e65d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3bdd39715a6e8591378d18fc9b74e807705bdb18"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3bdd39715a6e8591378d18fc9b74e807705bdb18",
"title": "3bdd39715a6e8591378d18fc9b74e807705bdb18",
"url": "https://llvm.googlesource.com/llvm-project/+/3bdd39715a6e8591378d18fc9b74e807705bdb18"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "193df2a12bb28d1e04217c1cdb39048c5d19aed7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@193df2a12bb28d1e04217c1cdb39048c5d19aed7",
"title": "193df2a12bb28d1e04217c1cdb39048c5d19aed7",
"url": "https://llvm.googlesource.com/llvm-project/+/193df2a12bb28d1e04217c1cdb39048c5d19aed7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6a571a1fb3d7bc58ae5f0ad8b85ece707ae5fcc3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6a571a1fb3d7bc58ae5f0ad8b85ece707ae5fcc3",
"title": "6a571a1fb3d7bc58ae5f0ad8b85ece707ae5fcc3",
"url": "https://llvm.googlesource.com/llvm-project/+/6a571a1fb3d7bc58ae5f0ad8b85ece707ae5fcc3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1d131ffff830c2fb6d953c6ae0b9b94003277a33"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1d131ffff830c2fb6d953c6ae0b9b94003277a33",
"title": "1d131ffff830c2fb6d953c6ae0b9b94003277a33",
"url": "https://llvm.googlesource.com/llvm-project/+/1d131ffff830c2fb6d953c6ae0b9b94003277a33"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "05e31438ac9491cfc72c48664480796de874c860"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@05e31438ac9491cfc72c48664480796de874c860",
"title": "05e31438ac9491cfc72c48664480796de874c860",
"url": "https://llvm.googlesource.com/llvm-project/+/05e31438ac9491cfc72c48664480796de874c860"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "355dbbc37c7221b5c8d55278b021b8b60c19ff2d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@355dbbc37c7221b5c8d55278b021b8b60c19ff2d",
"title": "355dbbc37c7221b5c8d55278b021b8b60c19ff2d",
"url": "https://llvm.googlesource.com/llvm-project/+/355dbbc37c7221b5c8d55278b021b8b60c19ff2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8cc77e748f4f6a0ddfb1e218f9e1e5c4ec4d5e6c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8cc77e748f4f6a0ddfb1e218f9e1e5c4ec4d5e6c",
"title": "8cc77e748f4f6a0ddfb1e218f9e1e5c4ec4d5e6c",
"url": "https://llvm.googlesource.com/llvm-project/+/8cc77e748f4f6a0ddfb1e218f9e1e5c4ec4d5e6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2f2a98bfb85fc6b3e620efe9d74bd0aeb602223c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2f2a98bfb85fc6b3e620efe9d74bd0aeb602223c",
"title": "2f2a98bfb85fc6b3e620efe9d74bd0aeb602223c",
"url": "https://llvm.googlesource.com/llvm-project/+/2f2a98bfb85fc6b3e620efe9d74bd0aeb602223c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0833493603e44d27771de23e9c925d04fb4a0525"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0833493603e44d27771de23e9c925d04fb4a0525",
"title": "0833493603e44d27771de23e9c925d04fb4a0525",
"url": "https://llvm.googlesource.com/llvm-project/+/0833493603e44d27771de23e9c925d04fb4a0525"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4c6a56225f3982ab2de7f8acb8d8560079a830c8"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4c6a56225f3982ab2de7f8acb8d8560079a830c8",
"title": "4c6a56225f3982ab2de7f8acb8d8560079a830c8",
"url": "https://llvm.googlesource.com/llvm-project/+/4c6a56225f3982ab2de7f8acb8d8560079a830c8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "fe6e17840159f7ae9785d8506ff2987a2df7ab08"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@fe6e17840159f7ae9785d8506ff2987a2df7ab08",
"title": "fe6e17840159f7ae9785d8506ff2987a2df7ab08",
"url": "https://llvm.googlesource.com/llvm-project/+/fe6e17840159f7ae9785d8506ff2987a2df7ab08"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a20fc93a65f763cc3f55988847b7294fcbdcb5db"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a20fc93a65f763cc3f55988847b7294fcbdcb5db",
"title": "a20fc93a65f763cc3f55988847b7294fcbdcb5db",
"url": "https://llvm.googlesource.com/llvm-project/+/a20fc93a65f763cc3f55988847b7294fcbdcb5db"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "a20fc93a65f763cc3f55988847b7294fcbdcb5db",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8869737107306249968"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-rust-llvm-ci-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:15:51.531] Scheduled build:
{
"id": "8704307045097583681",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-08T09:15:51.184425173Z",
"updateTime": "2025-09-08T09:15:51.184425173Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "a20fc93a65f763cc3f55988847b7294fcbdcb5db",
"ref": "refs/heads/main"
}
},
"canary": true
}
[09:15:51.531] Task URL: https://cr-buildbucket.appspot.com/build/8704307045097583681
[09:15:51.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:2:0) after 4m31s
[09:15:52.557] Received PubSub notification, asking Buildbucket for the build status
[09:15:52.574] Build status: SCHEDULED
[09:17:34.303] Received PubSub notification, asking Buildbucket for the build status
[09:17:34.396] Build status: STARTED
[09:20:22.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:2:0)
[09:20:22.617] Build status: STARTED
[09:20:22.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:5:0) after 5m1s
[09:25:23.632] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:5:0)
[09:25:23.655] Build status: STARTED
[09:25:23.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:6:0) after 8m45s
[09:34:08.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:6:0)
[09:34:08.781] Build status: STARTED
[09:34:08.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:7:0) after 5m8s
[09:39:16.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:7:0)
[09:39:16.870] Build status: STARTED
[09:39:16.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:8:0) after 3m49s
[09:43:05.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:8:0)
[09:43:05.995] Build status: STARTED
[09:43:05.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:9:0) after 7m33s
[09:50:39.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:9:0)
[09:50:39.030] Build status: STARTED
[09:50:39.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:10:0) after 7m51s
[09:58:30.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:10:0)
[09:58:30.192] Build status: STARTED
[09:58:30.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:11:0) after 3m42s
[10:02:12.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:11:0)
[10:02:12.229] Build status: STARTED
[10:02:12.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:12:0) after 4m13s
[10:06:25.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:12:0)
[10:06:25.259] Build status: STARTED
[10:06:25.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:13:0) after 2m5s
[10:08:30.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:13:0)
[10:08:30.323] Build status: STARTED
[10:08:30.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:14:0) after 1m53s
[10:10:23.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:14:0)
[10:10:23.366] Build status: STARTED
[10:10:23.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:15:0) after 4m20s
[10:14:43.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:15:0)
[10:14:43.535] Build status: STARTED
[10:14:43.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:16:0) after 2m0s
[10:16:43.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:16:0)
[10:16:43.569] Build status: STARTED
[10:16:43.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:17:0) after 9m35s
[10:26:18.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:17:0)
[10:26:18.608] Build status: STARTED
[10:26:18.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:18:0) after 7m55s
[10:34:13.634] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:18:0)
[10:34:13.670] Build status: STARTED
[10:34:13.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:19:0) after 7m30s
[10:41:43.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:19:0)
[10:41:43.813] Build status: STARTED
[10:41:43.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:20:0) after 1m30s
[10:43:13.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:20:0)
[10:43:13.964] Build status: STARTED
[10:43:13.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:21:0) after 3m10s
[10:46:23.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:21:0)
[10:46:24.005] Build status: STARTED
[10:46:24.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:22:0) after 5m48s
[10:52:12.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:22:0)
[10:52:12.076] Build status: STARTED
[10:52:12.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:23:0) after 4m30s
[10:56:42.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:23:0)
[10:56:42.159] Build status: STARTED
[10:56:42.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:24:0) after 1m41s
[10:58:23.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:24:0)
[10:58:23.200] Build status: STARTED
[10:58:23.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:25:0) after 7m18s
[11:05:41.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:25:0)
[11:05:41.219] Build status: STARTED
[11:05:41.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:26:0) after 8m14s
[11:13:55.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:26:0)
[11:13:55.218] Build status: STARTED
[11:13:55.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:27:0) after 5m18s
[11:19:13.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:27:0)
[11:19:13.353] Build status: STARTED
[11:19:13.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8869737107306249968:28:0) after 7m24s
[11:26:33.740] Received PubSub notification, asking Buildbucket for the build status
[11:26:33.769] Build:
{
"id": "8704307045097583681",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-08T09:15:51.184425173Z",
"startTime": "2025-09-08T09:17:33.817452154Z",
"endTime": "2025-09-08T11:26:33.382582324Z",
"updateTime": "2025-09-08T11:26:33.382582324Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "a20fc93a65f763cc3f55988847b7294fcbdcb5db",
"ref": "refs/heads/main"
}
},
"canary": true
}
[11:26:33.769] Invocation finished in 2h10m44.075646963s with status SUCCEEDED