[18:05:18.454] New invocation is queued and will start shortly
[18:05:18.454] Triggered by user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[18:05:19.495] Starting the invocation (attempt 1)
[18:05:19.525] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/7645269710493c188d1d270b9e4e085b3e92b9b0"
[18:05:19.525] Reconstructed gitiles commit from tags
[18:05:19.525] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:05:19.525] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:05:19.526] Buildbucket request:
{
"requestId": "8913280936190168336",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "b6886c4de79843feaa9beb1e95894c0b540fb7221fd52865bbd5c3cbde55b1d8/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fc668cdeb6ffc9b7d1c176ddb394af32ab9060df",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8913280936190168336",
"job": "fuchsia/clang_toolchain.ci.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "2f5ec356a6bb2872cf2e19c499481df5256496dcff6ff320d47c9eb9b8748d0e/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fc668cdeb6ffc9b7d1c176ddb394af32ab9060df",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "dceaa0f4491ebe30c0b0f1bc7fa5ec365b60ced6"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/dceaa0f4491ebe30c0b0f1bc7fa5ec365b60ced6",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "623ad8cf-9728-441b-b75f-21efd2220ccb",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "48059d8b6c94e4f48a72d60196a636693a68ac5628cc7945d68883a710f2f766/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fc668cdeb6ffc9b7d1c176ddb394af32ab9060df",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "74218a9c8fc4b0bdb4b2a4839455cf2f211a2a30"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/74218a9c8fc4b0bdb4b2a4839455cf2f211a2a30",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "a4ec6785-2e0e-4658-89cc-8a723ae16341",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "cb409154c886fd78db19aaa0bfb26216aa7ead122ebd5a27bf10a9cd7ec71640/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fc668cdeb6ffc9b7d1c176ddb394af32ab9060df",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "141391ad2f22885342935442642c6c892f43e1ed"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/141391ad2f22885342935442642c6c892f43e1ed",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "483369bb-d34c-47ed-95d1-dd72e802a5b5",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "b6886c4de79843feaa9beb1e95894c0b540fb7221fd52865bbd5c3cbde55b1d8/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "fc668cdeb6ffc9b7d1c176ddb394af32ab9060df",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "7645269710493c188d1d270b9e4e085b3e92b9b0"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/7645269710493c188d1d270b9e4e085b3e92b9b0",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "61a8240b-d45b-4ef7-b106-0244f616e6e6",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "7645269710493c188d1d270b9e4e085b3e92b9b0"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "7645269710493c188d1d270b9e4e085b3e92b9b0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913280936190168336"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang_toolchain.ci.core.x64-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"
}
}
[18:05:20.068] Scheduled build:
{
"id": "8747850874338084049",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-15T18:05:19.599178497Z",
"updateTime": "2024-05-15T18:05:19.599178497Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "7645269710493c188d1d270b9e4e085b3e92b9b0",
"ref": "refs/heads/main"
}
}
}
[18:05:20.068] Task URL: https://cr-buildbucket.appspot.com/build/8747850874338084049
[18:05:20.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:2:0) after 3m2s
[18:05:21.026] Received PubSub notification, asking Buildbucket for the build status
[18:05:21.047] Build status: SCHEDULED
[18:06:33.548] Received PubSub notification, asking Buildbucket for the build status
[18:06:33.578] Build status: STARTED
[18:08:22.099] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:2:0)
[18:08:22.130] Build status: STARTED
[18:08:22.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:5:0) after 9m54s
[18:18:16.155] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:5:0)
[18:18:16.172] Build status: STARTED
[18:18:16.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:6:0) after 5m32s
[18:23:48.193] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:6:0)
[18:23:48.213] Build status: STARTED
[18:23:48.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:7:0) after 3m3s
[18:26:51.240] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:7:0)
[18:26:51.297] Build status: STARTED
[18:26:51.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:8:0) after 3m49s
[18:30:40.373] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:8:0)
[18:30:40.388] Build status: STARTED
[18:30:40.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:9:0) after 5m59s
[18:36:39.415] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:9:0)
[18:36:39.439] Build status: STARTED
[18:36:39.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:10:0) after 9m2s
[18:45:41.466] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:10:0)
[18:45:41.492] Build status: STARTED
[18:45:41.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:11:0) after 7m33s
[18:53:14.592] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:11:0)
[18:53:14.630] Build status: STARTED
[18:53:14.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:12:0) after 2m44s
[18:55:58.677] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:12:0)
[18:55:58.712] Build status: STARTED
[18:55:58.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:13:0) after 1m19s
[18:57:17.799] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:13:0)
[18:57:17.849] Build status: STARTED
[18:57:17.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:14:0) after 1m4s
[18:58:21.868] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:14:0)
[18:58:21.898] Build status: STARTED
[18:58:21.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:15:0) after 8m19s
[19:06:40.947] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:15:0)
[19:06:41.073] Build status: STARTED
[19:06:41.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:16:0) after 2m57s
[19:09:38.119] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:16:0)
[19:09:38.137] Build status: STARTED
[19:09:38.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:17:0) after 4m40s
[19:14:18.214] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:17:0)
[19:14:18.235] Build status: STARTED
[19:14:18.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:18:0) after 4m5s
[19:18:23.263] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:18:0)
[19:18:23.298] Build status: STARTED
[19:18:23.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:19:0) after 5m5s
[19:23:28.281] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:19:0)
[19:23:28.312] Build status: STARTED
[19:23:28.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:20:0) after 7m24s
[19:30:52.364] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:20:0)
[19:30:52.504] Build status: STARTED
[19:30:52.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:21:0) after 8m23s
[19:39:15.524] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:21:0)
[19:39:15.544] Build status: STARTED
[19:39:15.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:22:0) after 9m41s
[19:48:56.629] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:22:0)
[19:48:56.648] Build status: STARTED
[19:48:56.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:23:0) after 2m22s
[19:51:18.791] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:23:0)
[19:51:18.818] Build status: STARTED
[19:51:18.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:24:0) after 2m1s
[19:53:19.936] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:24:0)
[19:53:19.984] Build status: STARTED
[19:53:19.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:25:0) after 2m22s
[19:55:42.068] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:25:0)
[19:55:42.086] Build status: STARTED
[19:55:42.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:26:0) after 4m11s
[19:59:53.171] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:26:0)
[19:59:53.264] Build status: STARTED
[19:59:53.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:27:0) after 3m52s
[20:03:45.394] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:27:0)
[20:03:45.420] Build status: STARTED
[20:03:45.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:28:0) after 7m31s
[20:11:16.615] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:28:0)
[20:11:16.642] Build status: STARTED
[20:11:16.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:29:0) after 9m50s
[20:21:06.618] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:29:0)
[20:21:06.726] Build status: STARTED
[20:21:06.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:30:0) after 8m31s
[20:29:37.761] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:30:0)
[20:29:37.811] Build status: STARTED
[20:29:37.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:31:0) after 5m48s
[20:35:25.844] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:31:0)
[20:35:25.877] Build status: STARTED
[20:35:25.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:32:0) after 4m4s
[20:39:29.900] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:32:0)
[20:39:29.977] Build status: STARTED
[20:39:29.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:33:0) after 2m30s
[20:42:00.043] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:33:0)
[20:42:00.070] Build status: STARTED
[20:42:00.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:34:0) after 6m29s
[20:48:29.068] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:34:0)
[20:48:29.088] Build status: STARTED
[20:48:29.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:35:0) after 4m11s
[20:52:40.113] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:35:0)
[20:52:40.154] Build status: STARTED
[20:52:40.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:36:0) after 2m32s
[20:55:12.140] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:36:0)
[20:55:12.242] Build status: STARTED
[20:55:12.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:37:0) after 6m48s
[21:02:00.328] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:37:0)
[21:02:00.435] Build status: STARTED
[21:02:00.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:38:0) after 6m46s
[21:08:46.462] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:38:0)
[21:08:46.488] Build status: STARTED
[21:08:46.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:39:0) after 8m7s
[21:16:53.527] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:39:0)
[21:16:53.547] Build status: STARTED
[21:16:53.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8913280936190168336:40:0) after 7m21s
[21:17:45.988] Received PubSub notification, asking Buildbucket for the build status
[21:17:46.017] Build:
{
"id": "8747850874338084049",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-15T18:05:19.599178497Z",
"startTime": "2024-05-15T18:06:32.958999242Z",
"endTime": "2024-05-15T21:17:45.419219340Z",
"updateTime": "2024-05-15T21:17:45.419219340Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "7645269710493c188d1d270b9e4e085b3e92b9b0",
"ref": "refs/heads/main"
}
}
}
[21:17:46.017] Invocation finished in 3h12m27.568797334s with status SUCCEEDED