[18:14:29.045] New invocation is queued and will start shortly
[18:14:30.299] Starting the invocation (attempt 1)
[18:14:30.330] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/8c716bec1dbd00267e2260d558df4d1ab0e5b506"
[18:14:30.331] Popped gitiles commit info from properties and tags
[18:14:30.331] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:14:30.331] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:14:30.331] Buildbucket request:
{
"requestId": "8870156202850133216",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-prod-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870156202850133216",
"job": "fuchsia/prod-rust-llvm-prod-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "96b1dfb57fc822ea69bf75a8f0a69eb168776faa"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@96b1dfb57fc822ea69bf75a8f0a69eb168776faa",
"title": "96b1dfb57fc822ea69bf75a8f0a69eb168776faa",
"url": "https://llvm.googlesource.com/llvm-project/+/96b1dfb57fc822ea69bf75a8f0a69eb168776faa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f5006e043143717e2e8ade9a009e1209b4a3563b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f5006e043143717e2e8ade9a009e1209b4a3563b",
"title": "f5006e043143717e2e8ade9a009e1209b4a3563b",
"url": "https://llvm.googlesource.com/llvm-project/+/f5006e043143717e2e8ade9a009e1209b4a3563b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "714f6b03bc89bff766ae628092cd059fd8aa0aa1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@714f6b03bc89bff766ae628092cd059fd8aa0aa1",
"title": "714f6b03bc89bff766ae628092cd059fd8aa0aa1",
"url": "https://llvm.googlesource.com/llvm-project/+/714f6b03bc89bff766ae628092cd059fd8aa0aa1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4829dedfa9bb3d1e15aaa88cf1ff329c7b0e59de"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4829dedfa9bb3d1e15aaa88cf1ff329c7b0e59de",
"title": "4829dedfa9bb3d1e15aaa88cf1ff329c7b0e59de",
"url": "https://llvm.googlesource.com/llvm-project/+/4829dedfa9bb3d1e15aaa88cf1ff329c7b0e59de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c93e2de67f3d4fb558edf9b69fd824b1fb9d29c2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c93e2de67f3d4fb558edf9b69fd824b1fb9d29c2",
"title": "c93e2de67f3d4fb558edf9b69fd824b1fb9d29c2",
"url": "https://llvm.googlesource.com/llvm-project/+/c93e2de67f3d4fb558edf9b69fd824b1fb9d29c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b176ba78c8ded98cca955d4d32234fac8ada506c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b176ba78c8ded98cca955d4d32234fac8ada506c",
"title": "b176ba78c8ded98cca955d4d32234fac8ada506c",
"url": "https://llvm.googlesource.com/llvm-project/+/b176ba78c8ded98cca955d4d32234fac8ada506c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4d72bb381bf2aff8aa84c0b70d8384b6fdf98d1d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4d72bb381bf2aff8aa84c0b70d8384b6fdf98d1d",
"title": "4d72bb381bf2aff8aa84c0b70d8384b6fdf98d1d",
"url": "https://llvm.googlesource.com/llvm-project/+/4d72bb381bf2aff8aa84c0b70d8384b6fdf98d1d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "adc0a2caff44e3884ce3189932fa0e9c8a28620d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@adc0a2caff44e3884ce3189932fa0e9c8a28620d",
"title": "adc0a2caff44e3884ce3189932fa0e9c8a28620d",
"url": "https://llvm.googlesource.com/llvm-project/+/adc0a2caff44e3884ce3189932fa0e9c8a28620d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "722339dc0927545834afffd8aca9a75efd450ca9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@722339dc0927545834afffd8aca9a75efd450ca9",
"title": "722339dc0927545834afffd8aca9a75efd450ca9",
"url": "https://llvm.googlesource.com/llvm-project/+/722339dc0927545834afffd8aca9a75efd450ca9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "878fa7b2686a8776d1f36a5bb516c95a07838825"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@878fa7b2686a8776d1f36a5bb516c95a07838825",
"title": "878fa7b2686a8776d1f36a5bb516c95a07838825",
"url": "https://llvm.googlesource.com/llvm-project/+/878fa7b2686a8776d1f36a5bb516c95a07838825"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ded5f433dedf4a3419548929ac3dfa826e11d2e1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ded5f433dedf4a3419548929ac3dfa826e11d2e1",
"title": "ded5f433dedf4a3419548929ac3dfa826e11d2e1",
"url": "https://llvm.googlesource.com/llvm-project/+/ded5f433dedf4a3419548929ac3dfa826e11d2e1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6aa9d928a86019ab8997fa9fb7c5533a67ed1a8d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6aa9d928a86019ab8997fa9fb7c5533a67ed1a8d",
"title": "6aa9d928a86019ab8997fa9fb7c5533a67ed1a8d",
"url": "https://llvm.googlesource.com/llvm-project/+/6aa9d928a86019ab8997fa9fb7c5533a67ed1a8d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2b9328c788f60d70f8cf021c56fc6dfa080e1c32"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2b9328c788f60d70f8cf021c56fc6dfa080e1c32",
"title": "2b9328c788f60d70f8cf021c56fc6dfa080e1c32",
"url": "https://llvm.googlesource.com/llvm-project/+/2b9328c788f60d70f8cf021c56fc6dfa080e1c32"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "83c1cf6251c95f8ffbe55238e43dc2d9fee9c8ea"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@83c1cf6251c95f8ffbe55238e43dc2d9fee9c8ea",
"title": "83c1cf6251c95f8ffbe55238e43dc2d9fee9c8ea",
"url": "https://llvm.googlesource.com/llvm-project/+/83c1cf6251c95f8ffbe55238e43dc2d9fee9c8ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4e36508e392ebfe7979b36ff65ecc4ddd49ee651"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4e36508e392ebfe7979b36ff65ecc4ddd49ee651",
"title": "4e36508e392ebfe7979b36ff65ecc4ddd49ee651",
"url": "https://llvm.googlesource.com/llvm-project/+/4e36508e392ebfe7979b36ff65ecc4ddd49ee651"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "be616b4c5b68d7832e46f70bde439b5be2f9e1cd"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@be616b4c5b68d7832e46f70bde439b5be2f9e1cd",
"title": "be616b4c5b68d7832e46f70bde439b5be2f9e1cd",
"url": "https://llvm.googlesource.com/llvm-project/+/be616b4c5b68d7832e46f70bde439b5be2f9e1cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8c716bec1dbd00267e2260d558df4d1ab0e5b506"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8c716bec1dbd00267e2260d558df4d1ab0e5b506",
"title": "8c716bec1dbd00267e2260d558df4d1ab0e5b506",
"url": "https://llvm.googlesource.com/llvm-project/+/8c716bec1dbd00267e2260d558df4d1ab0e5b506"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8c716bec1dbd00267e2260d558df4d1ab0e5b506",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870156202850133216"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-rust-llvm-prod-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:14:30.805] Scheduled build:
{
"id": "8704726140783593457",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-03T18:14:30.392652686Z",
"updateTime": "2025-09-03T18:14:30.392652686Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8c716bec1dbd00267e2260d558df4d1ab0e5b506",
"ref": "refs/heads/main"
}
}
}
[18:14:30.805] Task URL: https://cr-buildbucket.appspot.com/build/8704726140783593457
[18:14:30.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:2:0) after 7m15s
[18:14:31.802] Received PubSub notification, asking Buildbucket for the build status
[18:14:31.824] Build status: SCHEDULED
[18:21:45.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:2:0)
[18:21:45.980] Build status: SCHEDULED
[18:21:45.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:4:0) after 8m3s
[18:29:48.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:4:0)
[18:29:49.026] Build status: SCHEDULED
[18:29:49.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:5:0) after 9m54s
[18:39:43.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:5:0)
[18:39:43.130] Build status: SCHEDULED
[18:39:43.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:6:0) after 7m59s
[18:47:42.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:6:0)
[18:47:42.166] Build status: SCHEDULED
[18:47:42.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:7:0) after 5m38s
[18:53:20.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:7:0)
[18:53:20.348] Build status: SCHEDULED
[18:53:20.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:8:0) after 5m51s
[18:59:11.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:8:0)
[18:59:11.562] Build status: SCHEDULED
[18:59:11.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:9:0) after 2m19s
[19:01:30.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:9:0)
[19:01:30.600] Build status: SCHEDULED
[19:01:30.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:10:0) after 5m6s
[19:06:36.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:10:0)
[19:06:36.697] Build status: SCHEDULED
[19:06:36.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:11:0) after 5m53s
[19:12:29.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:11:0)
[19:12:29.771] Build status: SCHEDULED
[19:12:29.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:12:0) after 3m51s
[19:16:20.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:12:0)
[19:16:20.825] Build status: SCHEDULED
[19:16:20.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:13:0) after 8m9s
[19:24:29.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:13:0)
[19:24:29.996] Build status: SCHEDULED
[19:24:29.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:14:0) after 2m3s
[19:26:32.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:14:0)
[19:26:32.982] Build status: SCHEDULED
[19:26:32.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:15:0) after 7m59s
[19:28:31.462] Received PubSub notification, asking Buildbucket for the build status
[19:28:31.477] Build status: STARTED
[19:34:32.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:15:0)
[19:34:32.177] Build status: STARTED
[19:34:32.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:17:0) after 1m5s
[19:35:37.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:17:0)
[19:35:37.210] Build status: STARTED
[19:35:37.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:18:0) after 9m18s
[19:44:55.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:18:0)
[19:44:55.268] Build status: STARTED
[19:44:55.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:19:0) after 2m49s
[19:47:44.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:19:0)
[19:47:44.329] Build status: STARTED
[19:47:44.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:20:0) after 7m54s
[19:55:38.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:20:0)
[19:55:38.460] Build status: STARTED
[19:55:38.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:21:0) after 2m26s
[19:58:04.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:21:0)
[19:58:04.511] Build status: STARTED
[19:58:04.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:22:0) after 6m27s
[20:04:31.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:22:0)
[20:04:31.633] Build status: STARTED
[20:04:31.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:23:0) after 4m21s
[20:08:52.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:23:0)
[20:08:52.851] Build status: STARTED
[20:08:52.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:24:0) after 9m50s
[20:18:42.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:24:0)
[20:18:42.896] Build status: STARTED
[20:18:42.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:25:0) after 1m33s
[20:20:15.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:25:0)
[20:20:15.887] Build status: STARTED
[20:20:15.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:26:0) after 9m30s
[20:29:45.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:26:0)
[20:29:46.008] Build status: STARTED
[20:29:46.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:27:0) after 8m10s
[20:37:56.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:27:0)
[20:37:56.073] Build status: STARTED
[20:37:56.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:28:0) after 8m7s
[20:46:03.419] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:28:0)
[20:46:03.456] Build status: STARTED
[20:46:03.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:29:0) after 6m41s
[20:52:44.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:29:0)
[20:52:44.519] Build status: STARTED
[20:52:44.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8870156202850133216:30:0) after 8m27s
[20:53:36.931] Received PubSub notification, asking Buildbucket for the build status
[20:53:36.956] Build:
{
"id": "8704726140783593457",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-03T18:14:30.392652686Z",
"startTime": "2025-09-03T19:28:31.277051432Z",
"endTime": "2025-09-03T20:53:36.554822788Z",
"updateTime": "2025-09-03T20:53:36.554822788Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8c716bec1dbd00267e2260d558df4d1ab0e5b506",
"ref": "refs/heads/main"
}
}
}
[20:53:36.956] Invocation finished in 2h39m7.918015159s with status SUCCEEDED