[19:12:14.840] New invocation is queued and will start shortly
[19:12:14.840] Triggered by user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[19:12:16.091] Starting the invocation (attempt 1)
[19:12:16.129] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/873431a68a3aa3ec4fed5d2dc98ef527230b0d21"
[19:12:16.129] Reconstructed gitiles commit from tags
[19:12:16.129] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:12:16.129] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:12:16.130] Buildbucket request:
{
"requestId": "8914001500436964272",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.arm64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "a8cd976ce773053f1b319fdc603f975a29ae7fa8b4583cc0db9347f26af6baef/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fdbac53d0b3069a513a308170024a1d93507ba4a",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8914001500436964272",
"job": "fuchsia/clang_toolchain.ci.core.arm64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "f5c50a0c80e7f7830ed3733c1facb4094186058354327b975ee700b1bb79ac80/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fdbac53d0b3069a513a308170024a1d93507ba4a",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "5c5116556f58d90353aa3e3a34214cdc5ff0b2f2"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/5c5116556f58d90353aa3e3a34214cdc5ff0b2f2",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "93ad2695-7719-43c7-8777-d86cb4de511b",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "16f9ea4240f93bbbb8fa03bf7cf209c00e2595db244deca98d649ba5828d1e02/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fdbac53d0b3069a513a308170024a1d93507ba4a",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "72085698a244e10780a6f115269a2f88455c8cab"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/72085698a244e10780a6f115269a2f88455c8cab",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "b3f4f67f-cd4b-4bfb-8aff-1ab03a7168fb",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "585e248c17dc66adf05bbdcb7c6c04e06640c7bd0d04db38ae43e3ea45b78c69/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fdbac53d0b3069a513a308170024a1d93507ba4a",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "026a29e8b38aad79568de033d0e8e5d2e6bb4250"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/026a29e8b38aad79568de033d0e8e5d2e6bb4250",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "ce9893e9-1cc9-478d-82aa-5839947e7742",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "a8cd976ce773053f1b319fdc603f975a29ae7fa8b4583cc0db9347f26af6baef/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fdbac53d0b3069a513a308170024a1d93507ba4a",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "873431a68a3aa3ec4fed5d2dc98ef527230b0d21"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/873431a68a3aa3ec4fed5d2dc98ef527230b0d21",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "c429c134-3b4b-40f7-884c-ec4520a2d7f5",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "873431a68a3aa3ec4fed5d2dc98ef527230b0d21"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "873431a68a3aa3ec4fed5d2dc98ef527230b0d21",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8914001500436964272"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang_toolchain.ci.core.arm64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "clang-linux-x64"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:12:17.041] Scheduled build:
{
"id": "8748571438056324689",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-07T19:12:16.488389312Z",
"updateTime": "2024-05-07T19:12:16.488389312Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "873431a68a3aa3ec4fed5d2dc98ef527230b0d21",
"ref": "refs/heads/main"
}
},
"canary": true
}
[19:12:17.042] Task URL: https://cr-buildbucket.appspot.com/build/8748571438056324689
[19:12:17.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:2:0) after 8m57s
[19:12:17.939] Received PubSub notification, asking Buildbucket for the build status
[19:12:17.956] Build status: SCHEDULED
[19:13:46.968] Received PubSub notification, asking Buildbucket for the build status
[19:13:46.990] Build status: STARTED
[19:21:14.082] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:2:0)
[19:21:14.112] Build status: STARTED
[19:21:14.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:5:0) after 1m34s
[19:22:48.147] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:5:0)
[19:22:48.164] Build status: STARTED
[19:22:48.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:6:0) after 2m21s
[19:25:09.202] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:6:0)
[19:25:09.249] Build status: STARTED
[19:25:09.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:7:0) after 6m17s
[19:31:26.302] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:7:0)
[19:31:26.362] Build status: STARTED
[19:31:26.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:8:0) after 7m54s
[19:39:20.472] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:8:0)
[19:39:20.495] Build status: STARTED
[19:39:20.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:9:0) after 2m56s
[19:42:16.471] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:9:0)
[19:42:16.500] Build status: STARTED
[19:42:16.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:10:0) after 5m51s
[19:48:07.546] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:10:0)
[19:48:07.581] Build status: STARTED
[19:48:07.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:11:0) after 3m10s
[19:51:17.603] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:11:0)
[19:51:17.619] Build status: STARTED
[19:51:17.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:12:0) after 4m18s
[19:55:35.739] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:12:0)
[19:55:35.766] Build status: STARTED
[19:55:35.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:13:0) after 8m22s
[20:03:57.785] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:13:0)
[20:03:57.874] Build status: STARTED
[20:03:57.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:14:0) after 5m44s
[20:09:41.895] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:14:0)
[20:09:41.947] Build status: STARTED
[20:09:41.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:15:0) after 4m50s
[20:14:31.992] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:15:0)
[20:14:32.027] Build status: STARTED
[20:14:32.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:16:0) after 8m42s
[20:23:14.108] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:16:0)
[20:23:14.127] Build status: STARTED
[20:23:14.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:17:0) after 6m38s
[20:29:52.229] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:17:0)
[20:29:52.246] Build status: STARTED
[20:29:52.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:18:0) after 5m35s
[20:35:27.367] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:18:0)
[20:35:27.390] Build status: STARTED
[20:35:27.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:19:0) after 6m17s
[20:41:44.448] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:19:0)
[20:41:44.469] Build status: STARTED
[20:41:44.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:20:0) after 2m17s
[20:44:01.606] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:20:0)
[20:44:01.628] Build status: STARTED
[20:44:01.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:21:0) after 8m42s
[20:52:43.689] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:21:0)
[20:52:43.725] Build status: STARTED
[20:52:43.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:22:0) after 3m39s
[20:56:22.769] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:22:0)
[20:56:22.789] Build status: STARTED
[20:56:22.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:23:0) after 4m10s
[21:00:32.954] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:23:0)
[21:00:33.046] Build status: STARTED
[21:00:33.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:24:0) after 8m19s
[21:08:52.107] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:24:0)
[21:08:52.137] Build status: STARTED
[21:08:52.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:25:0) after 3m1s
[21:11:53.156] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:25:0)
[21:11:53.180] Build status: STARTED
[21:11:53.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:26:0) after 4m14s
[21:16:07.203] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:26:0)
[21:16:07.263] Build status: STARTED
[21:16:07.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:27:0) after 5m1s
[21:21:08.330] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:27:0)
[21:21:08.353] Build status: STARTED
[21:21:08.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:28:0) after 2m57s
[21:24:05.372] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:28:0)
[21:24:05.394] Build status: STARTED
[21:24:05.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:29:0) after 7m51s
[21:31:56.441] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:29:0)
[21:31:56.466] Build status: STARTED
[21:31:56.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:30:0) after 9m39s
[21:41:35.514] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:30:0)
[21:41:35.533] Build status: STARTED
[21:41:35.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:31:0) after 6m17s
[21:47:52.557] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:31:0)
[21:47:52.583] Build status: STARTED
[21:47:52.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:32:0) after 3m50s
[21:51:42.624] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:32:0)
[21:51:42.655] Build status: STARTED
[21:51:42.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:33:0) after 9m12s
[22:00:54.641] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:33:0)
[22:00:54.664] Build status: STARTED
[22:00:54.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.arm64-debug:8914001500436964272:34:0) after 6m32s
[22:04:50.346] Received PubSub notification, asking Buildbucket for the build status
[22:04:50.443] Build:
{
"id": "8748571438056324689",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-07T19:12:16.488389312Z",
"startTime": "2024-05-07T19:13:46.647115649Z",
"endTime": "2024-05-07T22:04:49.933995280Z",
"updateTime": "2024-05-07T22:04:49.933995280Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "873431a68a3aa3ec4fed5d2dc98ef527230b0d21",
"ref": "refs/heads/main"
}
},
"canary": true
}
[22:04:50.443] Invocation finished in 2h52m35.609888289s with status SUCCEEDED