[03:34:22.139] New invocation is queued and will start shortly
[03:34:23.225] Starting the invocation (attempt 1)
[03:34:23.252] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/3a682864d90e701f7a999d7e65ecb009786e87f3"
[03:34:23.252] Popped gitiles commit info from properties and tags
[03:34:23.252] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:34:23.253] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:34:23.254] Buildbucket request:
{
"requestId": "8872204708169485008",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-ci-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872204708169485008",
"job": "fuchsia/prod-rust-llvm-ci-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a9c5d33bb44c4bbb19f471d8b1a450c3cff355f7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a9c5d33bb44c4bbb19f471d8b1a450c3cff355f7",
"title": "a9c5d33bb44c4bbb19f471d8b1a450c3cff355f7",
"url": "https://llvm.googlesource.com/llvm-project/+/a9c5d33bb44c4bbb19f471d8b1a450c3cff355f7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ff5308692429b3628ad8679a532aa2fa1eefc9f1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ff5308692429b3628ad8679a532aa2fa1eefc9f1",
"title": "ff5308692429b3628ad8679a532aa2fa1eefc9f1",
"url": "https://llvm.googlesource.com/llvm-project/+/ff5308692429b3628ad8679a532aa2fa1eefc9f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "45d4e84454d6f9b7865c26a3482a6c15172526ab"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@45d4e84454d6f9b7865c26a3482a6c15172526ab",
"title": "45d4e84454d6f9b7865c26a3482a6c15172526ab",
"url": "https://llvm.googlesource.com/llvm-project/+/45d4e84454d6f9b7865c26a3482a6c15172526ab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ee56cb9c10bddf438c630353245abafdb32e6e9f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ee56cb9c10bddf438c630353245abafdb32e6e9f",
"title": "ee56cb9c10bddf438c630353245abafdb32e6e9f",
"url": "https://llvm.googlesource.com/llvm-project/+/ee56cb9c10bddf438c630353245abafdb32e6e9f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "713ec58dec1ec4d3866d62e563d7bd8e1ae3da53"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@713ec58dec1ec4d3866d62e563d7bd8e1ae3da53",
"title": "713ec58dec1ec4d3866d62e563d7bd8e1ae3da53",
"url": "https://llvm.googlesource.com/llvm-project/+/713ec58dec1ec4d3866d62e563d7bd8e1ae3da53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7e2cc725dbe00a9c99b0d58df020ab970e53dd51"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7e2cc725dbe00a9c99b0d58df020ab970e53dd51",
"title": "7e2cc725dbe00a9c99b0d58df020ab970e53dd51",
"url": "https://llvm.googlesource.com/llvm-project/+/7e2cc725dbe00a9c99b0d58df020ab970e53dd51"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6b09e9554252ae2c47dd97d9f97e64236ab32aaf"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6b09e9554252ae2c47dd97d9f97e64236ab32aaf",
"title": "6b09e9554252ae2c47dd97d9f97e64236ab32aaf",
"url": "https://llvm.googlesource.com/llvm-project/+/6b09e9554252ae2c47dd97d9f97e64236ab32aaf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3a682864d90e701f7a999d7e65ecb009786e87f3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3a682864d90e701f7a999d7e65ecb009786e87f3",
"title": "3a682864d90e701f7a999d7e65ecb009786e87f3",
"url": "https://llvm.googlesource.com/llvm-project/+/3a682864d90e701f7a999d7e65ecb009786e87f3"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3a682864d90e701f7a999d7e65ecb009786e87f3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872204708169485008"
},
{
"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"
}
}
[03:34:23.750] Scheduled build:
{
"id": "8706774646287370497",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-12T03:34:23.306113313Z",
"updateTime": "2025-08-12T03:34:23.306113313Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3a682864d90e701f7a999d7e65ecb009786e87f3",
"ref": "refs/heads/main"
}
}
}
[03:34:23.750] Task URL: https://cr-buildbucket.appspot.com/build/8706774646287370497
[03:34:23.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:2:0) after 7m35s
[03:34:33.200] Received PubSub notification, asking Buildbucket for the build status
[03:34:33.287] Build status: SCHEDULED
[03:41:58.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:2:0)
[03:41:58.789] Build status: SCHEDULED
[03:41:58.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:4:0) after 2m23s
[03:44:21.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:4:0)
[03:44:21.840] Build status: SCHEDULED
[03:44:21.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:5:0) after 6m28s
[03:50:49.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:5:0)
[03:50:49.934] Build status: SCHEDULED
[03:50:49.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:6:0) after 4m12s
[03:55:01.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:6:0)
[03:55:01.962] Build status: SCHEDULED
[03:55:01.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:7:0) after 5m30s
[04:00:31.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:7:0)
[04:00:32.013] Build status: SCHEDULED
[04:00:32.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:8:0) after 1m9s
[04:01:41.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:8:0)
[04:01:41.125] Build status: SCHEDULED
[04:01:41.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:9:0) after 8m48s
[04:10:29.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:9:0)
[04:10:29.168] Build status: SCHEDULED
[04:10:29.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:10:0) after 8m32s
[04:19:01.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:10:0)
[04:19:01.260] Build status: SCHEDULED
[04:19:01.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:11:0) after 7m25s
[04:26:26.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:11:0)
[04:26:26.325] Build status: SCHEDULED
[04:26:26.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:12:0) after 9m16s
[04:35:42.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:12:0)
[04:35:42.529] Build status: SCHEDULED
[04:35:42.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:13:0) after 3m20s
[04:39:02.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:13:0)
[04:39:02.599] Build status: SCHEDULED
[04:39:02.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:14:0) after 3m21s
[04:42:23.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:14:0)
[04:42:23.760] Build status: SCHEDULED
[04:42:23.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:15:0) after 8m31s
[04:50:54.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:15:0)
[04:50:54.931] Build status: SCHEDULED
[04:50:54.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:16:0) after 7m35s
[04:58:30.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:16:0)
[04:58:30.094] Build status: SCHEDULED
[04:58:30.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:17:0) after 9m42s
[05:08:12.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:17:0)
[05:08:12.265] Build status: SCHEDULED
[05:08:12.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:18:0) after 6m49s
[05:15:01.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:18:0)
[05:15:01.325] Build status: SCHEDULED
[05:15:01.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:19:0) after 5m27s
[05:20:28.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:19:0)
[05:20:28.416] Build status: SCHEDULED
[05:20:28.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:20:0) after 8m12s
[05:26:22.452] Received PubSub notification, asking Buildbucket for the build status
[05:26:22.467] Build status: STARTED
[05:28:40.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:20:0)
[05:28:40.546] Build status: STARTED
[05:28:40.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:22:0) after 1m42s
[05:30:22.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:22:0)
[05:30:22.568] Build status: STARTED
[05:30:22.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:23:0) after 4m0s
[05:34:22.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:23:0)
[05:34:22.664] Build status: STARTED
[05:34:22.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:24:0) after 5m1s
[05:39:23.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:24:0)
[05:39:23.736] Build status: STARTED
[05:39:23.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:25:0) after 7m6s
[05:46:29.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:25:0)
[05:46:29.815] Build status: STARTED
[05:46:29.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:26:0) after 2m18s
[05:48:47.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:26:0)
[05:48:47.867] Build status: STARTED
[05:48:47.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:27:0) after 1m11s
[05:49:58.890] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:27:0)
[05:49:58.917] Build status: STARTED
[05:49:58.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:28:0) after 1m22s
[05:51:20.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:28:0)
[05:51:20.975] Build status: STARTED
[05:51:20.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:29:0) after 2m23s
[05:53:44.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:29:0)
[05:53:44.049] Build status: STARTED
[05:53:44.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:30:0) after 6m34s
[06:00:18.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:30:0)
[06:00:18.159] Build status: STARTED
[06:00:18.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:31:0) after 3m35s
[06:03:53.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:31:0)
[06:03:53.224] Build status: STARTED
[06:03:53.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:32:0) after 6m12s
[06:10:05.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:32:0)
[06:10:05.288] Build status: STARTED
[06:10:05.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:33:0) after 4m28s
[06:14:33.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:33:0)
[06:14:33.451] Build status: STARTED
[06:14:33.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:34:0) after 7m45s
[06:22:18.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:34:0)
[06:22:18.530] Build status: STARTED
[06:22:18.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:35:0) after 4m41s
[06:26:59.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:35:0)
[06:26:59.601] Build status: STARTED
[06:26:59.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:36:0) after 3m57s
[06:30:56.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:36:0)
[06:30:56.631] Build status: STARTED
[06:30:56.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:37:0) after 9m56s
[06:40:52.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:37:0)
[06:40:52.701] Build status: STARTED
[06:40:52.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:38:0) after 1m15s
[06:42:07.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:38:0)
[06:42:07.736] Build status: STARTED
[06:42:07.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:39:0) after 7m0s
[06:49:07.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:39:0)
[06:49:07.766] Build status: STARTED
[06:49:07.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:40:0) after 5m4s
[06:54:11.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:40:0)
[06:54:11.804] Build status: STARTED
[06:54:11.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:41:0) after 6m31s
[07:00:42.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:41:0)
[07:00:42.841] Build status: STARTED
[07:00:42.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:42:0) after 2m35s
[07:03:18.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:42:0)
[07:03:18.217] Build status: STARTED
[07:03:18.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-llvm-ci-trigger-40be1bb5:8872204708169485008:43:0) after 8m42s
[07:08:23.865] Received PubSub notification, asking Buildbucket for the build status
[07:08:23.882] Build:
{
"id": "8706774646287370497",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-llvm-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-12T03:34:23.306113313Z",
"startTime": "2025-08-12T05:26:21.987674450Z",
"endTime": "2025-08-12T07:08:23.371775743Z",
"updateTime": "2025-08-12T07:08:23.371775743Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3a682864d90e701f7a999d7e65ecb009786e87f3",
"ref": "refs/heads/main"
}
}
}
[07:08:23.882] Invocation finished in 3h34m1.75388773s with status SUCCEEDED