[16:29:02.289] New invocation is queued and will start shortly
[16:29:02.289] Triggered by user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[16:29:03.372] Starting the invocation (attempt 1)
[16:29:03.402] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/98d8b688bd351850ca99d3e5e9dfdf582c83c4f4"
[16:29:03.402] Reconstructed gitiles commit from tags
[16:29:03.402] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:29:03.402] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:29:03.402] Buildbucket request:
{
"requestId": "8930409819589136576",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "bb18a424399f260f81d0a3239e9886190fef28e0cf71391c0e2b15e326c3a738/577"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "edf630549b5da9d7aa6115ffd61ba646f043243d",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8930409819589136576",
"job": "fuchsia/clang_toolchain.ci.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "ddac2cd905c850be67db2b2475df65d871ef0fd50f98334ef3084ec00cb8cdc1/577"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "edf630549b5da9d7aa6115ffd61ba646f043243d",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "b663d6a310ffc916f418fa378b47abd5213140ae"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/b663d6a310ffc916f418fa378b47abd5213140ae",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "f61bd634-8e49-4749-a658-7bbba9af099f",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "bb18a424399f260f81d0a3239e9886190fef28e0cf71391c0e2b15e326c3a738/577"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "edf630549b5da9d7aa6115ffd61ba646f043243d",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "98d8b688bd351850ca99d3e5e9dfdf582c83c4f4"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/98d8b688bd351850ca99d3e5e9dfdf582c83c4f4",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "1dd4246f-ebe5-4041-b7a2-e971c0cd32ca",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "98d8b688bd351850ca99d3e5e9dfdf582c83c4f4"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "98d8b688bd351850ca99d3e5e9dfdf582c83c4f4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8930409819589136576"
},
{
"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:29:03.909] Scheduled build:
{
"id": "8764979757701538641",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-08T16:29:03.467801164Z",
"updateTime": "2023-11-08T16:29:03.467801164Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "98d8b688bd351850ca99d3e5e9dfdf582c83c4f4",
"ref": "refs/heads/main"
}
}
}
[16:29:03.909] Task URL: https://cr-buildbucket.appspot.com/build/8764979757701538641
[16:29:03.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:2:0) after 4m23s
[16:29:04.837] Received PubSub notification, asking Buildbucket for the build status
[16:29:04.865] Build status: SCHEDULED
[16:29:05.265] Received PubSub notification, asking Buildbucket for the build status
[16:29:05.303] Build status: STARTED
[16:33:26.969] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:2:0)
[16:33:26.992] Build status: STARTED
[16:33:26.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:5:0) after 8m41s
[16:42:08.011] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:5:0)
[16:42:08.041] Build status: STARTED
[16:42:08.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:6:0) after 1m26s
[16:43:34.093] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:6:0)
[16:43:34.149] Build status: STARTED
[16:43:34.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:7:0) after 4m49s
[16:48:23.164] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:7:0)
[16:48:23.205] Build status: STARTED
[16:48:23.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:8:0) after 6m42s
[16:55:05.222] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:8:0)
[16:55:05.255] Build status: STARTED
[16:55:05.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:9:0) after 7m43s
[17:02:48.274] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:9:0)
[17:02:48.297] Build status: STARTED
[17:02:48.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:10:0) after 5m32s
[17:08:20.344] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:10:0)
[17:08:20.367] Build status: STARTED
[17:08:20.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:11:0) after 6m13s
[17:14:33.450] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:11:0)
[17:14:33.515] Build status: STARTED
[17:14:33.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:12:0) after 5m46s
[17:20:19.531] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:12:0)
[17:20:19.695] Build status: STARTED
[17:20:19.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:13:0) after 3m52s
[17:24:11.771] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:13:0)
[17:24:11.843] Build status: STARTED
[17:24:11.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:14:0) after 2m31s
[17:26:42.866] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:14:0)
[17:26:42.936] Build status: STARTED
[17:26:42.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:15:0) after 9m47s
[17:36:29.967] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:15:0)
[17:36:29.989] Build status: STARTED
[17:36:29.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:16:0) after 9m24s
[17:45:54.021] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:16:0)
[17:45:54.041] Build status: STARTED
[17:45:54.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:17:0) after 3m26s
[17:49:20.115] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:17:0)
[17:49:20.152] Build status: STARTED
[17:49:20.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:18:0) after 6m3s
[17:55:23.174] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:18:0)
[17:55:23.194] Build status: STARTED
[17:55:23.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:19:0) after 5m57s
[18:01:20.231] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:19:0)
[18:01:20.248] Build status: STARTED
[18:01:20.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:20:0) after 3m55s
[18:05:15.282] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:20:0)
[18:05:15.335] Build status: STARTED
[18:05:15.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:21:0) after 2m17s
[18:07:32.400] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:21:0)
[18:07:32.452] Build status: STARTED
[18:07:32.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:22:0) after 2m57s
[18:10:29.467] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:22:0)
[18:10:29.596] Build status: STARTED
[18:10:29.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:23:0) after 1m59s
[18:12:28.697] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:23:0)
[18:12:28.736] Build status: STARTED
[18:12:28.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:24:0) after 5m41s
[18:18:09.861] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:24:0)
[18:18:09.947] Build status: STARTED
[18:18:09.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:25:0) after 2m36s
[18:20:45.981] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:25:0)
[18:20:46.045] Build status: STARTED
[18:20:46.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:26:0) after 1m46s
[18:22:32.061] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:26:0)
[18:22:32.080] Build status: STARTED
[18:22:32.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:27:0) after 9m25s
[18:31:57.091] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:27:0)
[18:31:57.119] Build status: STARTED
[18:31:57.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8930409819589136576:28:0) after 9m7s
[18:31:58.575] Received PubSub notification, asking Buildbucket for the build status
[18:31:58.659] Build:
{
"id": "8764979757701538641",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-08T16:29:03.467801164Z",
"startTime": "2023-11-08T16:29:04.587894Z",
"endTime": "2023-11-08T18:31:57.644774Z",
"updateTime": "2023-11-08T18:31:57.644774Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "98d8b688bd351850ca99d3e5e9dfdf582c83c4f4",
"ref": "refs/heads/main"
}
}
}
[18:31:58.659] Invocation finished in 2h2m56.375746619s with status SUCCEEDED