[13:35:47.069] New invocation is queued and will start shortly
[13:35:48.131] Starting the invocation (attempt 1)
[13:35:48.153] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/6106b9473d980dcda5c92edd3944882232fa58aa"
[13:35:48.153] Popped gitiles commit info from properties and tags
[13:35:48.153] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:35:48.153] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:35:48.155] Buildbucket request:
{
"requestId": "8865009710035243968",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-integration-llvm-latest-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865009710035243968",
"job": "fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a8656c556610653bfb3dbdf408932da9eca8b8db"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a8656c556610653bfb3dbdf408932da9eca8b8db",
"title": "a8656c556610653bfb3dbdf408932da9eca8b8db",
"url": "https://llvm.googlesource.com/llvm-project/+/a8656c556610653bfb3dbdf408932da9eca8b8db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8c8beadcc7542c1481da6378c658d38c98896670"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8c8beadcc7542c1481da6378c658d38c98896670",
"title": "8c8beadcc7542c1481da6378c658d38c98896670",
"url": "https://llvm.googlesource.com/llvm-project/+/8c8beadcc7542c1481da6378c658d38c98896670"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a55a7207c7e4d98dad32e8d53dd5964ee833edd9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a55a7207c7e4d98dad32e8d53dd5964ee833edd9",
"title": "a55a7207c7e4d98dad32e8d53dd5964ee833edd9",
"url": "https://llvm.googlesource.com/llvm-project/+/a55a7207c7e4d98dad32e8d53dd5964ee833edd9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ea034477fd9b2205b5fa45028ae13e2c2b0467d1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ea034477fd9b2205b5fa45028ae13e2c2b0467d1",
"title": "ea034477fd9b2205b5fa45028ae13e2c2b0467d1",
"url": "https://llvm.googlesource.com/llvm-project/+/ea034477fd9b2205b5fa45028ae13e2c2b0467d1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5c5cef32607235f9c2b20a308ff5720dbb565c82"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5c5cef32607235f9c2b20a308ff5720dbb565c82",
"title": "5c5cef32607235f9c2b20a308ff5720dbb565c82",
"url": "https://llvm.googlesource.com/llvm-project/+/5c5cef32607235f9c2b20a308ff5720dbb565c82"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "da709f5b781680a8d0534ea8aedff6d0b9a7c04e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@da709f5b781680a8d0534ea8aedff6d0b9a7c04e",
"title": "da709f5b781680a8d0534ea8aedff6d0b9a7c04e",
"url": "https://llvm.googlesource.com/llvm-project/+/da709f5b781680a8d0534ea8aedff6d0b9a7c04e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "89540114a72594dbf71fbe728ba6c6d1deecfa03"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@89540114a72594dbf71fbe728ba6c6d1deecfa03",
"title": "89540114a72594dbf71fbe728ba6c6d1deecfa03",
"url": "https://llvm.googlesource.com/llvm-project/+/89540114a72594dbf71fbe728ba6c6d1deecfa03"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6106b9473d980dcda5c92edd3944882232fa58aa"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6106b9473d980dcda5c92edd3944882232fa58aa",
"title": "6106b9473d980dcda5c92edd3944882232fa58aa",
"url": "https://llvm.googlesource.com/llvm-project/+/6106b9473d980dcda5c92edd3944882232fa58aa"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6106b9473d980dcda5c92edd3944882232fa58aa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865009710035243968"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:35:48.588] Scheduled build:
{
"id": "8699579648175522321",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-integration-llvm-latest-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-10-30T13:35:48.212154021Z",
"updateTime": "2025-10-30T13:35:48.212154021Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6106b9473d980dcda5c92edd3944882232fa58aa",
"ref": "refs/heads/main"
}
}
}
[13:35:48.588] Task URL: https://cr-buildbucket.appspot.com/build/8699579648175522321
[13:35:48.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:2:0) after 1m20s
[13:35:49.588] Received PubSub notification, asking Buildbucket for the build status
[13:35:49.602] Build status: SCHEDULED
[13:37:08.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:2:0)
[13:37:08.596] Build status: SCHEDULED
[13:37:08.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:4:0) after 8m12s
[13:45:20.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:4:0)
[13:45:20.779] Build status: SCHEDULED
[13:45:20.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:5:0) after 6m37s
[13:51:57.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:5:0)
[13:51:57.857] Build status: SCHEDULED
[13:51:57.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:6:0) after 4m46s
[13:56:43.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:6:0)
[13:56:43.943] Build status: SCHEDULED
[13:56:43.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:7:0) after 3m1s
[13:59:44.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:7:0)
[13:59:44.983] Build status: SCHEDULED
[13:59:44.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:8:0) after 5m4s
[14:04:49.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:8:0)
[14:04:49.110] Build status: SCHEDULED
[14:04:49.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:9:0) after 7m43s
[14:12:32.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:9:0)
[14:12:32.142] Build status: SCHEDULED
[14:12:32.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:10:0) after 2m18s
[14:14:50.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:10:0)
[14:14:50.239] Build status: SCHEDULED
[14:14:50.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:11:0) after 9m41s
[14:24:31.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:11:0)
[14:24:31.260] Build status: SCHEDULED
[14:24:31.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:12:0) after 1m42s
[14:26:13.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:12:0)
[14:26:15.771] Build status: SCHEDULED
[14:26:15.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:13:0) after 9m26s
[14:35:41.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:13:0)
[14:35:41.853] Build status: SCHEDULED
[14:35:41.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-integration-llvm-latest-trigger-40be1bb5:8865009710035243968:14:0) after 4m53s
[14:38:31.016] Received PubSub notification, asking Buildbucket for the build status
[14:38:31.141] Build status: STARTED
[14:39:47.871] Received PubSub notification, asking Buildbucket for the build status
[14:39:47.950] Build:
{
"id": "8699579648175522321",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-integration-llvm-latest-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-10-30T13:35:48.212154021Z",
"startTime": "2025-10-30T14:38:30.683327760Z",
"endTime": "2025-10-30T14:39:47.591156539Z",
"updateTime": "2025-10-30T14:39:47.591156539Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6106b9473d980dcda5c92edd3944882232fa58aa",
"ref": "refs/heads/main"
}
}
}
[14:39:47.950] Invocation finished in 1h4m0.895686113s with status SUCCEEDED