[06:50:42.932] New invocation is queued and will start shortly
[06:50:44.103] Starting the invocation (attempt 1)
[06:50:44.133] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc"
[06:50:44.133] Popped gitiles commit info from properties and tags
[06:50:44.133] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:50:44.133] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:50:44.133] Buildbucket request:
{
"requestId": "8882067424451682224",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882067424451682224",
"job": "fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "82f1f5e5d4104fa276386f4840eacef9c44becce"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@82f1f5e5d4104fa276386f4840eacef9c44becce",
"title": "82f1f5e5d4104fa276386f4840eacef9c44becce",
"url": "https://llvm.googlesource.com/llvm-project/+/82f1f5e5d4104fa276386f4840eacef9c44becce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5717c5f385d3b8f5b395c3013aaba9929a618aa3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5717c5f385d3b8f5b395c3013aaba9929a618aa3",
"title": "5717c5f385d3b8f5b395c3013aaba9929a618aa3",
"url": "https://llvm.googlesource.com/llvm-project/+/5717c5f385d3b8f5b395c3013aaba9929a618aa3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6d99d1a4a93564ab7a128c269b2c55dcbd4f9570"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6d99d1a4a93564ab7a128c269b2c55dcbd4f9570",
"title": "6d99d1a4a93564ab7a128c269b2c55dcbd4f9570",
"url": "https://llvm.googlesource.com/llvm-project/+/6d99d1a4a93564ab7a128c269b2c55dcbd4f9570"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3b58a60086cb755fe40cb90a73d27a83d44ce766"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3b58a60086cb755fe40cb90a73d27a83d44ce766",
"title": "3b58a60086cb755fe40cb90a73d27a83d44ce766",
"url": "https://llvm.googlesource.com/llvm-project/+/3b58a60086cb755fe40cb90a73d27a83d44ce766"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "38ad9266b6705818544c03856eeef8335961dc22"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@38ad9266b6705818544c03856eeef8335961dc22",
"title": "38ad9266b6705818544c03856eeef8335961dc22",
"url": "https://llvm.googlesource.com/llvm-project/+/38ad9266b6705818544c03856eeef8335961dc22"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9c2190eb5dacfb7991f616c83a9e87740c1c6d49"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9c2190eb5dacfb7991f616c83a9e87740c1c6d49",
"title": "9c2190eb5dacfb7991f616c83a9e87740c1c6d49",
"url": "https://llvm.googlesource.com/llvm-project/+/9c2190eb5dacfb7991f616c83a9e87740c1c6d49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "46f91173c5cd0b7aadeb1bc462217a020567d20e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@46f91173c5cd0b7aadeb1bc462217a020567d20e",
"title": "46f91173c5cd0b7aadeb1bc462217a020567d20e",
"url": "https://llvm.googlesource.com/llvm-project/+/46f91173c5cd0b7aadeb1bc462217a020567d20e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "71329c6da1cd467f23b63e4a2956420f45b2cee9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@71329c6da1cd467f23b63e4a2956420f45b2cee9",
"title": "71329c6da1cd467f23b63e4a2956420f45b2cee9",
"url": "https://llvm.googlesource.com/llvm-project/+/71329c6da1cd467f23b63e4a2956420f45b2cee9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc",
"title": "8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc",
"url": "https://llvm.googlesource.com/llvm-project/+/8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882067424451682224"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:50:44.477] Scheduled build:
{
"id": "8716637362469163441",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-25T06:50:44.194691455Z",
"updateTime": "2025-04-25T06:50:44.194691455Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[06:50:44.477] Task URL: https://cr-buildbucket.appspot.com/build/8716637362469163441
[06:50:44.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:2:0) after 6m3s
[06:50:45.467] Received PubSub notification, asking Buildbucket for the build status
[06:50:45.484] Build status: SCHEDULED
[06:56:47.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:2:0)
[06:56:47.820] Build status: SCHEDULED
[06:56:47.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:4:0) after 9m43s
[06:58:54.301] Received PubSub notification, asking Buildbucket for the build status
[06:58:54.332] Build status: STARTED
[07:06:30.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:4:0)
[07:06:30.916] Build status: STARTED
[07:06:30.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:6:0) after 1m35s
[07:08:06.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:6:0)
[07:08:06.170] Build status: STARTED
[07:08:06.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:7:0) after 8m35s
[07:16:41.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:7:0)
[07:16:41.245] Build status: STARTED
[07:16:41.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:8:0) after 2m3s
[07:18:44.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:8:0)
[07:18:44.298] Build status: STARTED
[07:18:44.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:9:0) after 8m51s
[07:27:35.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:9:0)
[07:27:35.347] Build status: STARTED
[07:27:35.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:10:0) after 3m7s
[07:30:42.367] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:10:0)
[07:30:42.395] Build status: STARTED
[07:30:42.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:11:0) after 8m24s
[07:39:06.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:11:0)
[07:39:06.507] Build status: STARTED
[07:39:06.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:12:0) after 7m42s
[07:46:48.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:12:0)
[07:46:48.554] Build status: STARTED
[07:46:48.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:13:0) after 3m27s
[07:50:15.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:13:0)
[07:50:15.818] Build status: STARTED
[07:50:15.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:14:0) after 2m18s
[07:52:34.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:14:0)
[07:52:34.076] Build status: STARTED
[07:52:34.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:15:0) after 9m54s
[08:02:28.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:15:0)
[08:02:28.602] Build status: STARTED
[08:02:28.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:16:0) after 9m6s
[08:11:34.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:16:0)
[08:11:34.650] Build status: STARTED
[08:11:34.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:17:0) after 4m8s
[08:15:42.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:17:0)
[08:15:42.688] Build status: STARTED
[08:15:42.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:18:0) after 5m18s
[08:21:00.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:18:0)
[08:21:00.828] Build status: STARTED
[08:21:00.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:19:0) after 6m54s
[08:27:54.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:19:0)
[08:27:54.854] Build status: STARTED
[08:27:54.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:20:0) after 3m15s
[08:31:09.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:20:0)
[08:31:10.208] Build status: STARTED
[08:31:10.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:21:0) after 8m37s
[08:39:47.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:21:0)
[08:39:47.268] Build status: STARTED
[08:39:47.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:22:0) after 9m41s
[08:49:28.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:22:0)
[08:49:28.330] Build status: STARTED
[08:49:28.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:23:0) after 7m4s
[08:56:32.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:23:0)
[08:56:32.441] Build status: STARTED
[08:56:32.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:24:0) after 4m43s
[09:01:15.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:24:0)
[09:01:15.476] Build status: STARTED
[09:01:15.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:25:0) after 6m10s
[09:07:25.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:25:0)
[09:07:25.514] Build status: STARTED
[09:07:25.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:26:0) after 4m45s
[09:12:10.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:26:0)
[09:12:10.558] Build status: STARTED
[09:12:10.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:27:0) after 9m32s
[09:21:42.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:27:0)
[09:21:42.616] Build status: STARTED
[09:21:42.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:28:0) after 7m22s
[09:29:04.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:28:0)
[09:29:04.717] Build status: STARTED
[09:29:04.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:29:0) after 5m14s
[09:34:18.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:29:0)
[09:34:18.791] Build status: STARTED
[09:34:18.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:30:0) after 8m56s
[09:43:14.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:30:0)
[09:43:14.842] Build status: STARTED
[09:43:14.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:31:0) after 6m12s
[09:49:26.868] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:31:0)
[09:49:26.899] Build status: STARTED
[09:49:26.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:32:0) after 6m29s
[09:55:55.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:32:0)
[09:55:55.977] Build status: STARTED
[09:55:55.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:33:0) after 4m16s
[10:00:11.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:33:0)
[10:00:12.025] Build status: STARTED
[10:00:12.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:34:0) after 3m11s
[10:03:23.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:34:0)
[10:03:23.065] Build status: STARTED
[10:03:23.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:35:0) after 4m26s
[10:07:49.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:35:0)
[10:07:49.219] Build status: STARTED
[10:07:49.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:36:0) after 7m16s
[10:15:05.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:36:0)
[10:15:05.487] Build status: STARTED
[10:15:05.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:37:0) after 6m4s
[10:21:09.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:37:0)
[10:21:09.670] Build status: STARTED
[10:21:09.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:38:0) after 9m54s
[10:31:03.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:38:0)
[10:31:03.722] Build status: STARTED
[10:31:03.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:39:0) after 4m5s
[10:35:08.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:39:0)
[10:35:08.767] Build status: STARTED
[10:35:08.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:40:0) after 9m38s
[10:44:46.935] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:40:0)
[10:44:46.950] Build status: STARTED
[10:44:46.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:41:0) after 7m37s
[10:52:23.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:41:0)
[10:52:24.014] Build status: STARTED
[10:52:24.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8882067424451682224:42:0) after 4m40s
[10:54:54.568] Received PubSub notification, asking Buildbucket for the build status
[10:54:54.590] Build:
{
"id": "8716637362469163441",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-25T06:50:44.194691455Z",
"startTime": "2025-04-25T06:58:54.074700291Z",
"endTime": "2025-04-25T10:54:54.139459950Z",
"updateTime": "2025-04-25T10:54:54.139459950Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8c2233b304dcdac0a6edb61a3cc5f9cb97c382bc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[10:54:54.590] Invocation finished in 4h4m11.671344822s with status SUCCEEDED