[13:33:57.331] New invocation is queued and will start shortly
[13:33:58.626] Starting the invocation (attempt 1)
[13:33:58.654] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/dba8acde6d6a320dad42cdbfe7c8261938348e23"
[13:33:58.654] Popped gitiles commit info from properties and tags
[13:33:58.654] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:33:58.655] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:33:58.655] Buildbucket request:
{
"requestId": "8882313845677158480",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-prod-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882313845677158480",
"job": "fuchsia/prod-rust-llvm-prod-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3d91a71223801bb73ab3e4ff8ab3f883639ed79f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3d91a71223801bb73ab3e4ff8ab3f883639ed79f",
"title": "3d91a71223801bb73ab3e4ff8ab3f883639ed79f",
"url": "https://llvm.googlesource.com/llvm-project/+/3d91a71223801bb73ab3e4ff8ab3f883639ed79f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a25fdd7aca240c5e1a1051ee0509c9c6a4440009"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a25fdd7aca240c5e1a1051ee0509c9c6a4440009",
"title": "a25fdd7aca240c5e1a1051ee0509c9c6a4440009",
"url": "https://llvm.googlesource.com/llvm-project/+/a25fdd7aca240c5e1a1051ee0509c9c6a4440009"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7f684c9fca76a166efe4e03c6e2bc969e8aed81b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7f684c9fca76a166efe4e03c6e2bc969e8aed81b",
"title": "7f684c9fca76a166efe4e03c6e2bc969e8aed81b",
"url": "https://llvm.googlesource.com/llvm-project/+/7f684c9fca76a166efe4e03c6e2bc969e8aed81b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e428afdfcf56ccadbbcff16e8fe52e51622baed7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e428afdfcf56ccadbbcff16e8fe52e51622baed7",
"title": "e428afdfcf56ccadbbcff16e8fe52e51622baed7",
"url": "https://llvm.googlesource.com/llvm-project/+/e428afdfcf56ccadbbcff16e8fe52e51622baed7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "587206a442ebb656f9d72e7e0cc5845ef3a2f7ed"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@587206a442ebb656f9d72e7e0cc5845ef3a2f7ed",
"title": "587206a442ebb656f9d72e7e0cc5845ef3a2f7ed",
"url": "https://llvm.googlesource.com/llvm-project/+/587206a442ebb656f9d72e7e0cc5845ef3a2f7ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "47903e3372b8ab942f0d36f135edba5a8de07cf9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@47903e3372b8ab942f0d36f135edba5a8de07cf9",
"title": "47903e3372b8ab942f0d36f135edba5a8de07cf9",
"url": "https://llvm.googlesource.com/llvm-project/+/47903e3372b8ab942f0d36f135edba5a8de07cf9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ec3a90509d7db3b32d6102351c86470d9799dc9b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ec3a90509d7db3b32d6102351c86470d9799dc9b",
"title": "ec3a90509d7db3b32d6102351c86470d9799dc9b",
"url": "https://llvm.googlesource.com/llvm-project/+/ec3a90509d7db3b32d6102351c86470d9799dc9b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2b712693886a2a8339c0452f12f06b676e946d49"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2b712693886a2a8339c0452f12f06b676e946d49",
"title": "2b712693886a2a8339c0452f12f06b676e946d49",
"url": "https://llvm.googlesource.com/llvm-project/+/2b712693886a2a8339c0452f12f06b676e946d49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2e145f11c0bcfa2052416d96d682c75f33971a8c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2e145f11c0bcfa2052416d96d682c75f33971a8c",
"title": "2e145f11c0bcfa2052416d96d682c75f33971a8c",
"url": "https://llvm.googlesource.com/llvm-project/+/2e145f11c0bcfa2052416d96d682c75f33971a8c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "974a8ccb2b9f5b930ce47f65122b5a86481e57fe"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@974a8ccb2b9f5b930ce47f65122b5a86481e57fe",
"title": "974a8ccb2b9f5b930ce47f65122b5a86481e57fe",
"url": "https://llvm.googlesource.com/llvm-project/+/974a8ccb2b9f5b930ce47f65122b5a86481e57fe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a5cdbef5f06904fab27219955604a2759e4dd482"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a5cdbef5f06904fab27219955604a2759e4dd482",
"title": "a5cdbef5f06904fab27219955604a2759e4dd482",
"url": "https://llvm.googlesource.com/llvm-project/+/a5cdbef5f06904fab27219955604a2759e4dd482"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dba8acde6d6a320dad42cdbfe7c8261938348e23"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dba8acde6d6a320dad42cdbfe7c8261938348e23",
"title": "dba8acde6d6a320dad42cdbfe7c8261938348e23",
"url": "https://llvm.googlesource.com/llvm-project/+/dba8acde6d6a320dad42cdbfe7c8261938348e23"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "dba8acde6d6a320dad42cdbfe7c8261938348e23",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882313845677158480"
},
{
"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"
}
}
[13:33:59.066] Scheduled build:
{
"id": "8716883783582227905",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-22T13:33:58.707913977Z",
"updateTime": "2025-04-22T13:33:58.707913977Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "dba8acde6d6a320dad42cdbfe7c8261938348e23",
"ref": "refs/heads/main"
}
}
}
[13:33:59.066] Task URL: https://cr-buildbucket.appspot.com/build/8716883783582227905
[13:33:59.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:2:0) after 9m54s
[13:33:59.401] Received PubSub notification, asking Buildbucket for the build status
[13:33:59.427] Build status: SCHEDULED
[13:43:53.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:2:0)
[13:43:53.477] Build status: SCHEDULED
[13:43:53.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:4:0) after 3m10s
[13:47:03.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:4:0)
[13:47:03.520] Build status: SCHEDULED
[13:47:03.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:5:0) after 2m42s
[13:49:45.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:5:0)
[13:49:45.569] Build status: SCHEDULED
[13:49:45.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:6:0) after 4m3s
[13:53:48.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:6:0)
[13:53:48.717] Build status: SCHEDULED
[13:53:48.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:7:0) after 9m44s
[14:03:32.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:7:0)
[14:03:32.829] Build status: SCHEDULED
[14:03:32.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:8:0) after 7m38s
[14:11:10.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:8:0)
[14:11:10.967] Build status: SCHEDULED
[14:11:10.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:9:0) after 6m16s
[14:14:10.689] Received PubSub notification, asking Buildbucket for the build status
[14:14:10.712] Build status: STARTED
[14:17:26.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:9:0)
[14:17:27.021] Build status: STARTED
[14:17:27.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:11:0) after 2m0s
[14:19:27.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:11:0)
[14:19:27.059] Build status: STARTED
[14:19:27.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:12:0) after 1m35s
[14:21:02.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:12:0)
[14:21:02.265] Build status: STARTED
[14:21:02.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:13:0) after 5m14s
[14:26:16.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:13:0)
[14:26:16.322] Build status: STARTED
[14:26:16.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:14:0) after 9m19s
[14:35:35.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:14:0)
[14:35:35.481] Build status: STARTED
[14:35:35.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:15:0) after 7m1s
[14:42:36.549] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:15:0)
[14:42:36.575] Build status: STARTED
[14:42:36.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:16:0) after 4m4s
[14:46:40.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:16:0)
[14:46:40.621] Build status: STARTED
[14:46:40.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:17:0) after 3m32s
[14:50:12.647] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:17:0)
[14:50:12.671] Build status: STARTED
[14:50:12.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:18:0) after 1m55s
[14:52:07.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:18:0)
[14:52:07.733] Build status: STARTED
[14:52:07.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:19:0) after 1m30s
[14:53:37.787] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:19:0)
[14:53:37.804] Build status: STARTED
[14:53:37.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:20:0) after 6m29s
[15:00:06.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:20:0)
[15:00:06.883] Build status: STARTED
[15:00:06.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:21:0) after 2m44s
[15:02:50.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:21:0)
[15:02:50.928] Build status: STARTED
[15:02:50.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:22:0) after 5m22s
[15:08:12.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:22:0)
[15:08:13.016] Build status: STARTED
[15:08:13.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:23:0) after 9m38s
[15:17:51.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:23:0)
[15:17:51.112] Build status: STARTED
[15:17:51.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:24:0) after 9m7s
[15:26:58.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:24:0)
[15:26:58.154] Build status: STARTED
[15:26:58.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:25:0) after 6m52s
[15:33:50.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:25:0)
[15:33:50.266] Build status: STARTED
[15:33:50.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:26:0) after 8m40s
[15:42:30.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:26:0)
[15:42:30.317] Build status: STARTED
[15:42:30.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:27:0) after 8m38s
[15:51:08.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:27:0)
[15:51:08.364] Build status: STARTED
[15:51:08.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-prod-trigger-40be1bb5:8882313845677158480:28:0) after 9m11s
[15:52:06.223] Received PubSub notification, asking Buildbucket for the build status
[15:52:06.245] Build:
{
"id": "8716883783582227905",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-22T13:33:58.707913977Z",
"startTime": "2025-04-22T14:14:08.998355006Z",
"endTime": "2025-04-22T15:52:05.865280876Z",
"updateTime": "2025-04-22T15:52:05.865280876Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "dba8acde6d6a320dad42cdbfe7c8261938348e23",
"ref": "refs/heads/main"
}
}
}
[15:52:06.245] Invocation finished in 2h18m8.918965854s with status SUCCEEDED