[16:02:04.915] New invocation is queued and will start shortly
[16:02:04.915] Triggered by user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[16:02:05.982] Starting the invocation (attempt 1)
[16:02:06.013] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/8eccf2b872cc1a88a1e5d4e5af0bfdabfb66c7bb"
[16:02:06.013] Reconstructed gitiles commit from tags
[16:02:06.013] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:02:06.014] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:02:06.014] Buildbucket request:
{
"requestId": "8926515845982042464",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "79264e613fde3c8c2455774fe87e1792ca0926c288fffdd810cb936c81607642/578"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "24cd25d944780276a5ab08a9ee7e35331abb602a",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8926515845982042464",
"job": "fuchsia/clang_toolchain.ci.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "5787225c08cad4c85189095a5ecca33a644dd7934b71a62b10543d724ec7973e/578"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "24cd25d944780276a5ab08a9ee7e35331abb602a",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "dddb9d1ee3e283133ce1abb50b7c7a3715317b9d"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/dddb9d1ee3e283133ce1abb50b7c7a3715317b9d",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "f9de4463-d119-4513-aee5-328fda6f5ac9",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "a887e64d4ef29fa8c4b41ecb8b7437e4d945a0663d7bce6f6945693656a9012b/578"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "24cd25d944780276a5ab08a9ee7e35331abb602a",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "2fe94cead0a55d8d269e6e32bb95f7aa987d4db8"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/2fe94cead0a55d8d269e6e32bb95f7aa987d4db8",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "d09324bd-4961-4df7-b25a-f381114aac94",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "79264e613fde3c8c2455774fe87e1792ca0926c288fffdd810cb936c81607642/578"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "24cd25d944780276a5ab08a9ee7e35331abb602a",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "8eccf2b872cc1a88a1e5d4e5af0bfdabfb66c7bb"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/8eccf2b872cc1a88a1e5d4e5af0bfdabfb66c7bb",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "6c0329b6-995a-437c-b64e-5e61000167ef",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "8eccf2b872cc1a88a1e5d4e5af0bfdabfb66c7bb"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8eccf2b872cc1a88a1e5d4e5af0bfdabfb66c7bb",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8926515845982042464"
},
{
"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"
}
}
[16:02:06.465] Scheduled build:
{
"id": "8761085784101922849",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-21T16:02:06.078082221Z",
"updateTime": "2023-12-21T16:02:06.078082221Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8eccf2b872cc1a88a1e5d4e5af0bfdabfb66c7bb",
"ref": "refs/heads/main"
}
}
}
[16:02:06.465] Task URL: https://cr-buildbucket.appspot.com/build/8761085784101922849
[16:02:06.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:2:0) after 2m52s
[16:02:06.783] Received PubSub notification, asking Buildbucket for the build status
[16:02:06.809] Build status: SCHEDULED
[16:02:44.265] Received PubSub notification, asking Buildbucket for the build status
[16:02:44.393] Build status: STARTED
[16:04:58.874] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:2:0)
[16:04:58.908] Build status: STARTED
[16:04:58.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:5:0) after 2m6s
[16:07:04.991] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:5:0)
[16:07:05.013] Build status: STARTED
[16:07:05.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:6:0) after 8m45s
[16:15:50.090] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:6:0)
[16:15:50.117] Build status: STARTED
[16:15:50.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:7:0) after 5m21s
[16:21:11.133] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:7:0)
[16:21:11.158] Build status: STARTED
[16:21:11.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:8:0) after 4m2s
[16:25:13.199] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:8:0)
[16:25:13.232] Build status: STARTED
[16:25:13.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:9:0) after 8m18s
[16:33:31.254] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:9:0)
[16:33:31.277] Build status: STARTED
[16:33:31.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:10:0) after 8m16s
[16:41:47.302] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:10:0)
[16:41:47.322] Build status: STARTED
[16:41:47.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:11:0) after 6m30s
[16:48:17.403] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:11:0)
[16:48:17.433] Build status: STARTED
[16:48:17.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:12:0) after 6m41s
[16:54:58.808] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:12:0)
[16:54:58.828] Build status: STARTED
[16:54:58.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:13:0) after 5m52s
[17:00:50.859] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:13:0)
[17:00:50.892] Build status: STARTED
[17:00:50.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:14:0) after 6m34s
[17:07:25.247] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:14:0)
[17:07:25.284] Build status: STARTED
[17:07:25.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:15:0) after 8m2s
[17:15:27.301] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:15:0)
[17:15:27.373] Build status: STARTED
[17:15:27.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:16:0) after 5m55s
[17:21:22.443] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:16:0)
[17:21:22.465] Build status: STARTED
[17:21:22.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:17:0) after 9m29s
[17:30:51.539] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:17:0)
[17:30:51.644] Build status: STARTED
[17:30:51.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:18:0) after 2m57s
[17:33:48.813] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:18:0)
[17:33:48.832] Build status: STARTED
[17:33:48.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:19:0) after 9m48s
[17:43:36.861] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:19:0)
[17:43:36.912] Build status: STARTED
[17:43:36.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:20:0) after 3m25s
[17:47:02.042] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:20:0)
[17:47:02.061] Build status: STARTED
[17:47:02.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:21:0) after 2m19s
[17:49:21.075] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:21:0)
[17:49:21.097] Build status: STARTED
[17:49:21.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:22:0) after 9m9s
[17:58:30.417] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:22:0)
[17:58:30.443] Build status: STARTED
[17:58:30.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8926515845982042464:23:0) after 5m29s
[18:00:44.108] Received PubSub notification, asking Buildbucket for the build status
[18:00:44.147] Build:
{
"id": "8761085784101922849",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-21T16:02:06.078082221Z",
"startTime": "2023-12-21T16:02:43.280618Z",
"endTime": "2023-12-21T18:00:41.835132Z",
"updateTime": "2023-12-21T18:00:41.835132Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "8eccf2b872cc1a88a1e5d4e5af0bfdabfb66c7bb",
"ref": "refs/heads/main"
}
}
}
[18:00:44.147] Invocation finished in 1h58m39.246535377s with status SUCCEEDED